25 Jul
2003
25 Jul
'03
8:49 p.m.
Hi!
I'm using todays CVS for ser and sems on redhat 9. As database module, I use
mysql. When ser routes a call to sems, sems fails with "signal 17 received".
The log of ans_machine and ser are following, attached are the config files.
both fifo are read/write for everybody.
The CVS version of sems didn't compiled and stoped with an error in
ans_machine.cpp: 'pair' was not declared in this scope
I solved this error by adding
#include <vector.h in ans_machine.cpp.
Could this be the cause of the error?
regards and thank you for any hints,
Klaus
----------------
ans_machine log
----------------
./ans_machine -D 7 -E
Configuration:
configuration file: /etc/sems.conf
Ser's FIFO: /tmp/ser_fifo
our FIFO: /tmp/am_fifo
plug-in path: lib
daemon mode: 0
local IP: 128.131.80.136
(7522) INFO: run (AmServer.cpp:143): Loading application plug-ins
(7522) DEBUG: load (AmPlugIn.cpp:83): loading lib/apps/announcement.so ...
(7522) DEBUG: loadAppPlugIn (AmPlugIn.cpp:262): application 'announcement'
loaded.
(7522) DEBUG: reloadModuleConfig (SemsConfiguration.cpp:70): using inline
configuration for module announcement
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key: >>fork<<
value: >>yes<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
(7522) DEBUG: run (AmServer.cpp:175): fct_name= <sip_request.voicemail (7522) DEBUG: execute (AmServer.cpp:230):
cmd.method= <INVITE (7522) DEBUG: execute
(AmServer.cpp:231): cmd.user= <testuser
(7522) DEBUG: execute (AmServer.cpp:232): cmd.email=
<darilion(a)ict.tuwien.ac.at (7522) DEBUG: execute
(AmServer.cpp:233): cmd.domain=
<obelix.ict.tuwien.ac.at (7522) DEBUG: execute
(AmServer.cpp:234): cmd.dstip= <128.131.80.136
(7522) DEBUG: execute (AmServer.cpp:235): cmd.port= <5060 (7522) DEBUG: execute (AmServer.cpp:236):
cmd.r_uri=
<sip:testuser@obelix.ict.tuwien.ac.at
(7522) DEBUG: execute (AmServer.cpp:237): cmd.from_uri=
<sip:128.131.80.218:6997 (7522) DEBUG: execute
(AmServer.cpp:238): cmd.from=
<"testuser2(a)obelix.ict.tuwien.ac.at"
<sip:testuser2@obelix.ict.tuwien.ac.at>
(7522) DEBUG: execute (AmServer.cpp:239): cmd.to=
<<sip:testuser@obelix.ict.tuwien.ac.at>
(7522) DEBUG: execute (AmServer.cpp:240): cmd.callid=
<c88b8a17f734476d9fe80a16c44dd43c(a)128.131.80.218
(7522) DEBUG: execute (AmServer.cpp:241): cmd.from_tag=
<4f793f7afa1a4824976ad05508a58f6e
(7522) DEBUG: execute (AmServer.cpp:242): cmd.to_tag= < (7522) DEBUG: execute (AmServer.cpp:245):
cseq_str= <1 (7522) DEBUG: execute
(AmServer.cpp:250): cseq= <1>(1)
(7522) DEBUG: execute (AmServer.cpp:252): cmd.key= <64319:207254857 (7522) DEBUG: execute (AmServer.cpp:253):
cmd.route= < (7522) DEBUG: execute
(AmServer.cpp:254): cmd.next_hop= <
(7522) DEBUG: execute (AmServer.cpp:260): hdrs: `'
(7522) DEBUG: execute (AmServer.cpp:267): body: `v=0
o=- 0 0 IN IP4 128.131.80.218
s=session
c=IN IP4 128.131.80.218
b=CT:1000
t=0 0
m=audio 33678 RTP/AVP 97 111 112 6 0 8 4 5 3 101
k=base64:qNIq14h/mD0Jkylif9937MdxeXqvCF2SRHLus9Pl03o
a=rtpmap:97 red/8000
a=rtpmap:111 SIREN/16000
a=fmtp:111 bitrate=16000
a=rtpmap:112 G7221/16000
a=fmtp:112 bitrate=24000
a=rtpmap:6 DVI4/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=encryption:optional
'
(7522) DEBUG: execute (AmServer.cpp:278): everything is OK !
(7522) DEBUG: onInvite (AnswerMachine.cpp:92): email address for user
'testuser': darilion(a)ict.tuwien.ac.at
(7521) DEBUG: sig_usr (ans_machine.cpp:71): signal 17 received
[root@obelix answer_machine]#
----------------------------------------------------------------------------
-------------
ser log (only the part with the request) (there was also REGISTER request in
between)
----------------------------------------------------------------------------
-------------
0(7525) SIP Request:
0(7525) method: <INVITE 0(7525) uri:
<sip:testuser@obelix.ict.tuwien.ac.at
0(7525) version: <SIP/2.0
0(7525) parse_headers: flags=1
0(7525) end of header reached, state=5
0(7525) parse_headers: Via found, flags=1
0(7525) parse_headers: this is the first via
0(7525) After parse_msg...
0(7525) preparing to run routing scripts...
0(7525) lookup(): 'testuser' Not found in usrloc
0(7525) DEBUG : is_maxfwd_present: searching for max_forwards header
0(7525) parse_headers: flags=128
0(7525) DEBUG: is_maxfwd_present: value = 70
0(7525) parse_headers: flags=8
0(7525) DEBUG: add_param: tag=4f793f7afa1a4824976ad05508a58f6e
0(7525) DEBUG: add_param: epid=88cfc9fc75
0(7525) end of header reached, state=29
0(7525) parse_headers: flags=256
0(7525) end of header reached, state=9
0(7525) DEBUG: get_hdr_field: <To> [40];
uri=[sip:testuser@obelix.ict.tuwien.ac.at]
0(7525) DEBUG: to body [<sip:testuser@obelix.ict.tuwien.ac.at ]
0(7525) get_hdr_field: cseq <CSeq>: <1> <INVITE 0(7525) DEBUG: get_hdr_body :
content_length=527
0(7525) found end of header
0(7525) find_first_route(): No Route headers found
0(7525) loose_route(): There is no Route HF
0(7525) check_self - checking if host==us: 23==14 &&
[obelix.ict.tuwien.ac.at] == [128.131.80.136]
0(7525) DEBUG: t_addifnew: msg id=1 , global msg id=0 , T on
entrance=0xffffffff
0(7525) parse_headers: flags=-1
0(7525) parse_headers: flags=60
0(7525) t_lookup_request: start searching: hash=64319, isACK=0
0(7525) DEBUG: proceeding to pre-RFC3261 transaction matching
0(7525) DEBUG: t_lookup_request: no transaction found
0(7525) DEBUG: t_check: msg id=1 global id=1 T start=0x422b90f0
0(7525) DEBUG: t_check: T alredy found!
0(7525) parse_headers: flags=-1
0(7525) check_via_address(128.131.80.218, 128.131.80.218, 0)
0(7525) DEBUG: reply sent out. buf=0x80b9020: SIP/2.0 1...,
shmem=0x422ba1d8: SIP/2.0 1
0(7525) DEBUG: t_reply: finished
0(7525) **************** vm start - begin ******************
0(7525) parse_headers: flags=-1
0(7525) DEBUG: t_check: msg id=1 global id=1 T start=0x422b90f0
0(7525) DEBUG: t_check: T alredy found!
0(7525) DEBUG: vm: ******* contacts: *******
0(7525) DEBUG: sip:128.131.80.218:6997
0(7525) DEBUG: vm: **** end of contacts ****
0(7525) vm: calculated route:
0(7525) vm: next r-uri: sip:128.131.80.218:6997
0(7525) parse_headers: flags=-1
0(7525) vm: write_to_vm_fifo: <0.2
sip_request.voicemail
INVITE
testuser
darilion(a)ict.tuwien.ac.at
obelix.ict.tuwien.ac.at
128.131.80.136
5060
sip:testuser@obelix.ict.tuwien.ac.at
sip:128.131.80.218:6997
"testuser2(a)obelix.ict.tuwien.ac.at"
<sip:testuser2@obelix.ict.tuwien.ac.at
<sip:testuser@obelix.ict.tuwien.ac.at
c88b8a17f734476d9fe80a16c44dd43c(a)128.131.80.218
4f793f7afa1a4824976ad05508a58f6e
.
1
64319:207254857
.
.
.
v=0
o=- 0 0 IN IP4 128.131.80.218
s=session
c=IN IP4 128.131.80.218
b=CT:1000
t=0 0
m=audio 33678 RTP/AVP 97 111 112 6 0 8 4 5 3 101
k=base64:qNIq14h/mD0Jkylif9937MdxeXqvCF2SRHLus9Pl03o
a=rtpmap:97 red/8000
a=rtpmap:111 SIREN/16000
a=fmtp:111 bitrate=16000
a=rtpmap:112 G7221/16000
a=fmtp:112 bitrate=24000
a=rtpmap:6 DVI4/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=encryption:optional
0(7525) DEBUG: write_to_vm_fifo: write
completed
0(7525) DEBUG: add_to_tail_of_timer[0]: 0x422b923c
0(7525) **************** vm start - end ******************
0(7525) receive_msg: cleaning up
0(7525) SIP Request:
0(7525) method: <REGISTER 0(7525) uri:
<sip:obelix.ict.tuwien.ac.at 0(7525) version:
<SIP/2.0 0(7525)
parse_headers: flags=1
0(7525) end of header reached, state=5
0(7525) parse_headers: Via found, flags=1
0(7525) parse_headers: this is the first via
0(7525) After parse_msg...
0(7525) preparing to run routing scripts...
0(7525) lookup(): '' Not found in usrloc
0(7525) DEBUG : is_maxfwd_present: searching for max_forwards header
0(7525) parse_headers: flags=128
0(7525) end of header reached, state=9
0(7525) DEBUG: get_hdr_field: <To> [37];
uri=[sip:kurth@obelix.ict.tuwien.ac.at]
0(7525) DEBUG: to body [<sip:kurth@obelix.ict.tuwien.ac.at ]
0(7525) get_hdr_field: cseq <CSeq>: <1> <REGISTER 0(7525) DEBUG: get_hdr_body : content_length=0
0(7525) found end of header
0(7525) DEBUG: is_maxfwd_present: max_forwards header not found!
0(7525) DEBUG: add_param: tag=0422f6c4-5608-4e5f-9526-79275e6d920e
0(7525) end of header reached, state=29
0(7525) parse_headers: flags=256
0(7525) find_first_route(): No Route headers found
0(7525) loose_route(): There is no Route HF
0(7525) parse_headers: flags=-1
0(7525) check_via_address(128.131.80.37, 128.131.80.37, 0)
0(7525) receive_msg: cleaning up
1(7527) DEBUG: timer routine:0,tl=0x422b923c next=(nil)
1(7527) DEBUG: FR_handler:stop retr. and send CANCEL (0x422b90f0)
1(7527) ->>>>>>>>> T_code=100, new_code=408
1(7527) DEBUG: relay_reply: branch=0, save=0, relay=0
1(7527) parse_headers: flags=-1
1(7527) check_via_address(128.131.80.218, 128.131.80.218, 0)
1(7527) DEBUG: reply relayed. buf=0x80b8778: SIP/2.0 4...,
shmem=0x422ba1d8: SIP/2.0 4
1(7527) DEBUG: add_to_tail_of_timer[4]: 0x422b91a8
1(7527) DEBUG: add_to_tail_of_timer[0]: 0x422b91c0
1(7527) DEBUG: final_response_handler : done
0(7525) SIP Request:
0(7525) method: <ACK 0(7525) uri:
<sip:testuser@obelix.ict.tuwien.ac.at
0(7525) version: <SIP/2.0
0(7525) parse_headers: flags=1
0(7525) end of header reached, state=5
0(7525) parse_headers: Via found, flags=1
0(7525) parse_headers: this is the first via
0(7525) After parse_msg...
0(7525) DEBUG : sl_filter_ACK: to late to be a local ACK!
0(7525) preparing to run routing scripts...
0(7525) lookup(): 'testuser' Not found in usrloc
0(7525) DEBUG : is_maxfwd_present: searching for max_forwards header
0(7525) parse_headers: flags=128
0(7525) DEBUG: is_maxfwd_present: value = 70
0(7525) parse_headers: flags=8
0(7525) DEBUG: add_param: tag=4f793f7afa1a4824976ad05508a58f6e
0(7525) DEBUG: add_param: epid=88cfc9fc75
0(7525) end of header reached, state=29
0(7525) parse_headers: flags=256
0(7525) DEBUG: add_param: tag=58ff021f53ab5af6efbf188c434a3c48-8561
0(7525) end of header reached, state=29
0(7525) DEBUG: get_hdr_field: <To> [82];
uri=[sip:testuser@obelix.ict.tuwien.ac.at]
0(7525) DEBUG: to body [<sip:testuser@obelix.ict.tuwien.ac.at>]
0(7525) get_hdr_field: cseq <CSeq>: <1> <ACK
0(7525) DEBUG: get_hdr_body : content_length=0
0(7525) found end of header
0(7525) find_first_route(): No Route headers found
0(7525) loose_route(): There is no Route HF
0(7525) check_self - checking if host==us: 23==14 &&
[obelix.ict.tuwien.ac.at] == [128.131.80.136]
0(7525) DEBUG: t_addifnew: msg id=3 , global msg id=1 , T on
entrance=0xffffffff
0(7525) parse_headers: flags=-1
0(7525) parse_headers: flags=60
0(7525) t_lookup_request: start searching: hash=64319, isACK=1
0(7525) DEBUG: proceeding to pre-RFC3261 transaction matching
0(7525) DEBUG: non-2xx ACK matched
0(7525) DEBUG: t_lookup_request: transaction found (T=0x422b90f0)
0(7525) DEBUG: cleanup_uacs: RETR/FR timers reset
0(7525) DEBUG: add_to_tail_of_timer[2]: 0x422b9138
0(7525) receive_msg: cleaning up
1(7527) DEBUG: timer routine:0,tl=0x422b91c0 next=(nil)
1(7527) DEBUG: timer routine:4,tl=0x422b91a8 next=(nil)
1(7527) DEBUG: timer routine:2,tl=0x422b9138 next=(nil)
1(7527) DEBUG: wait_handler : removing 0x422b90f0 from table
1(7527) DEBUG: delete transaction 0x422b90f0
1(7527) DEBUG: wait_handler : done
>stderr<< value: >>no<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>loglevel<< value: >>1<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>fifo_name<< value:
>>/tmp/am_fifo<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: key:
>ser_fifo_name<< value:
>>/tmp/ser_fifo<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: key:
>plugin_path<< value: >>lib<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>smtp_server<< value:
>>localhost<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: key:
>smtp_port<< value: >>25<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>config.voicemail<< value:
>>inline<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:155): entered
inline module
configuration for voicemail
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>announce_path<< value:
>>/var/greetings/obelix.ict.tuwien.ac.at/<<
(7522) DEBUG: reloadFile
(SemsConfiguration.cpp:135): module: voicemail key:
>default_announce<< value:
>>default.wav<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: voicemail key:
>max_record_time<< value: >>30<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>config.voicemail<< value: >>end<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:167): leaving inline module
configuration for voicemail
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>config.announcement<< value:
>>inline<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:155): entered
inline module
configuration for announcement
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: announcement
key: >announce_path<< value:
>>/var/greetings/obelix.ict.tuwien.ac.at/<<
(7522) DEBUG: reloadFile
(SemsConfiguration.cpp:135): module: announcement
key: >default_announce<< value:
>>default.wav<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: announcement
key: >>config.announcement<< value: >>end<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:167): leaving inline module
configuration for announcement
(7522) DEBUG: load (AmPlugIn.cpp:83): loading lib/apps/echo.so ...
(7522) DEBUG: loadAppPlugIn (AmPlugIn.cpp:262): application 'echo' loaded.
(7522) DEBUG: load (AmPlugIn.cpp:83): loading lib/apps/voicemail.so ...
(7522) DEBUG: loadAppPlugIn (AmPlugIn.cpp:262): application 'voicemail'
loaded.
(7522) DEBUG: reloadModuleConfig (SemsConfiguration.cpp:70): using inline
configuration for module voicemail
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key: >>fork<<
value: >>yes<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>stderr<< value: >>no<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>loglevel<< value: >>1<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>fifo_name<< value:
>>/tmp/am_fifo<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: key:
>ser_fifo_name<< value:
>>/tmp/ser_fifo<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: key:
>plugin_path<< value: >>lib<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>smtp_server<< value:
>>localhost<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: key:
>smtp_port<< value: >>25<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>config.voicemail<< value:
>>inline<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:155): entered
inline module
configuration for voicemail
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>announce_path<< value:
>>/var/greetings/obelix.ict.tuwien.ac.at/<<
(7522) DEBUG: reloadFile
(SemsConfiguration.cpp:135): module: voicemail key:
>default_announce<< value:
>>default.wav<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: voicemail key:
>max_record_time<< value: >>30<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>config.voicemail<< value: >>end<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:167): leaving inline module
configuration for voicemail
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>config.announcement<< value:
>>inline<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:155): entered
inline module
configuration for announcement
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: announcement
key: >announce_path<< value:
>>/var/greetings/obelix.ict.tuwien.ac.at/<<
(7522) DEBUG: reloadFile
(SemsConfiguration.cpp:135): module: announcement
key: >default_announce<< value:
>>default.wav<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: announcement
key: >>config.announcement<< value: >>end<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:167): leaving inline module
configuration for announcement
(7522) INFO: run (AmServer.cpp:147): Loading audio plug-ins
(7522) DEBUG: load (AmPlugIn.cpp:83): loading lib/audio/gsm.so ...
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:199): codec id 3 inserted
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:214): payload 'GSM'inserted with
id 3
(7522) DEBUG: load (AmPlugIn.cpp:83): loading lib/audio/wav.so ...
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:199): codec id 1 inserted
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:199): codec id 2 inserted
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:214): payload 'PCMU'inserted
with id 0
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:214): payload 'PCMU'inserted
with id 8
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:238): file format Wav inserted
(7522) INFO: run (AmServer.cpp:151): Starting session container
(7522) INFO: run (AmServer.cpp:154): Starting mailer
(7522) INFO: run (AmServer.cpp:157): FIFO waiting for work...
(7522) DEBUG: run (AmServer.cpp:170): version= <0.2
27 Jul
27 Jul
3:33 a.m.
Hi Klaus,
thanks for reporting, this should be fixed now, could you please try again
after cvs update?
Please check, that the you have the glib2-devel rpm installed. Voicemail works
for me on a fully patched rh9.
Regards,
Uli.
Am Freitag, 25. Juli 2003 19:49 schrieb Klaus Darilion:
Hi!
I'm using todays CVS for ser and sems on redhat 9. As database module, I
use mysql. When ser routes a call to sems, sems fails with "signal 17
received".
The log of ans_machine and ser are following, attached are the config
files. both fifo are read/write for everybody.
The CVS version of sems didn't compiled and stoped with an error in
ans_machine.cpp: 'pair' was not declared in this scope
I solved this error by adding
#include <vector.h>
in ans_machine.cpp. Could this be the cause of the error?
regards and thank you for any hints,
Klaus
----------------
ans_machine log
----------------
./ans_machine -D 7 -E
Configuration:
configuration file: /etc/sems.conf
Ser's FIFO: /tmp/ser_fifo
our FIFO: /tmp/am_fifo
plug-in path: lib
daemon mode: 0
local IP: 128.131.80.136
(7522) INFO: run (AmServer.cpp:143): Loading application plug-ins
(7522) DEBUG: load (AmPlugIn.cpp:83): loading lib/apps/announcement.so ...
(7522) DEBUG: loadAppPlugIn (AmPlugIn.cpp:262): application 'announcement'
loaded.
(7522) DEBUG: reloadModuleConfig (SemsConfiguration.cpp:70): using inline
configuration for module announcement
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>fork<< value: >>yes<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>stderr<< value: >>no<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>loglevel<< value: >>1<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>fifo_name<< value:
>>/tmp/am_fifo<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>ser_fifo_name<< value:
>>/tmp/ser_fifo<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>plugin_path<< value: >>lib<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>smtp_server<< value:
>>localhost<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>smtp_port<< value: >>25<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>config.voicemail<< value:
>>inline<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:155): entered inline module
configuration for voicemail
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>announce_path<< value:
>>/var/greetings/obelix.ict.tuwien.ac.at/<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>default_announce<< value:
>>default.wav<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>max_record_time<< value:
>>30<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>config.voicemail<< value:
>>end<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:167): leaving inline module
configuration for voicemail
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>config.announcement<< value:
>>inline<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:155): entered inline module
configuration for announcement
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: announcement
key: >announce_path<< value:
>>/var/greetings/obelix.ict.tuwien.ac.at/<<
(7522) DEBUG: reloadFile
(SemsConfiguration.cpp:135): module: announcement
key: >default_announce<< value:
>>default.wav<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: announcement
key: >>config.announcement<< value: >>end<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:167): leaving inline module
configuration for announcement
(7522) DEBUG: load (AmPlugIn.cpp:83): loading lib/apps/echo.so ...
(7522) DEBUG: loadAppPlugIn (AmPlugIn.cpp:262): application 'echo' loaded.
(7522) DEBUG: load (AmPlugIn.cpp:83): loading lib/apps/voicemail.so ...
(7522) DEBUG: loadAppPlugIn (AmPlugIn.cpp:262): application 'voicemail'
loaded.
(7522) DEBUG: reloadModuleConfig (SemsConfiguration.cpp:70): using inline
configuration for module voicemail
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>fork<< value: >>yes<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>stderr<< value: >>no<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>loglevel<< value: >>1<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>fifo_name<< value:
>>/tmp/am_fifo<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>ser_fifo_name<< value:
>>/tmp/ser_fifo<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>plugin_path<< value: >>lib<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>smtp_server<< value:
>>localhost<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>smtp_port<< value: >>25<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>config.voicemail<< value:
>>inline<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:155): entered inline module
configuration for voicemail
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>announce_path<< value:
>>/var/greetings/obelix.ict.tuwien.ac.at/<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>default_announce<< value:
>>default.wav<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>max_record_time<< value:
>>30<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: voicemail key:
>config.voicemail<< value:
>>end<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:167): leaving inline module
configuration for voicemail
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: key:
>config.announcement<< value:
>>inline<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:155): entered inline module
configuration for announcement
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135): module: announcement
key: >announce_path<< value:
>>/var/greetings/obelix.ict.tuwien.ac.at/<<
(7522) DEBUG: reloadFile
(SemsConfiguration.cpp:135): module: announcement
key: >default_announce<< value:
>>default.wav<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:135):
module: announcement
key: >>config.announcement<< value: >>end<<
(7522) DEBUG: reloadFile (SemsConfiguration.cpp:167): leaving inline module
configuration for announcement
(7522) INFO: run (AmServer.cpp:147): Loading audio plug-ins
(7522) DEBUG: load (AmPlugIn.cpp:83): loading lib/audio/gsm.so ...
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:199): codec id 3 inserted
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:214): payload 'GSM'inserted
with id 3
(7522) DEBUG: load (AmPlugIn.cpp:83): loading lib/audio/wav.so ...
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:199): codec id 1 inserted
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:199): codec id 2 inserted
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:214): payload 'PCMU'inserted
with id 0
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:214): payload 'PCMU'inserted
with id 8
(7522) DEBUG: loadAudioPlugIn (AmPlugIn.cpp:238): file format Wav inserted
(7522) INFO: run (AmServer.cpp:151): Starting session container
(7522) INFO: run (AmServer.cpp:154): Starting mailer
(7522) INFO: run (AmServer.cpp:157): FIFO waiting for work...
(7522) DEBUG: run (AmServer.cpp:170): version= <0.2>
(7522) DEBUG: run (AmServer.cpp:175): fct_name= <sip_request.voicemail>
(7522) DEBUG: execute (AmServer.cpp:230): cmd.method= <INVITE>
(7522) DEBUG: execute (AmServer.cpp:231): cmd.user= <testuser>
(7522) DEBUG: execute (AmServer.cpp:232): cmd.email=
<darilion(a)ict.tuwien.ac.at>
(7522) DEBUG: execute (AmServer.cpp:233): cmd.domain=
<obelix.ict.tuwien.ac.at>
(7522) DEBUG: execute (AmServer.cpp:234): cmd.dstip= <128.131.80.136>
(7522) DEBUG: execute (AmServer.cpp:235): cmd.port= <5060>
(7522) DEBUG: execute (AmServer.cpp:236): cmd.r_uri=
<sip:testuser@obelix.ict.tuwien.ac.at>
(7522) DEBUG: execute (AmServer.cpp:237): cmd.from_uri=
<sip:128.131.80.218:6997>
(7522) DEBUG: execute (AmServer.cpp:238): cmd.from=
<"testuser2(a)obelix.ict.tuwien.ac.at"
<sip:testuser2@obelix.ict.tuwien.ac.at>>
(7522) DEBUG: execute (AmServer.cpp:239): cmd.to=
<<sip:testuser@obelix.ict.tuwien.ac.at>>
(7522) DEBUG: execute (AmServer.cpp:240): cmd.callid=
<c88b8a17f734476d9fe80a16c44dd43c(a)128.131.80.218>
(7522) DEBUG: execute (AmServer.cpp:241): cmd.from_tag=
<4f793f7afa1a4824976ad05508a58f6e>
(7522) DEBUG: execute (AmServer.cpp:242): cmd.to_tag= <>
(7522) DEBUG: execute (AmServer.cpp:245): cseq_str= <1>
(7522) DEBUG: execute (AmServer.cpp:250): cseq= <1>(1)
(7522) DEBUG: execute (AmServer.cpp:252): cmd.key= <64319:207254857>
(7522) DEBUG: execute (AmServer.cpp:253): cmd.route= <>
(7522) DEBUG: execute (AmServer.cpp:254): cmd.next_hop= <>
(7522) DEBUG: execute (AmServer.cpp:260): hdrs: `'
(7522) DEBUG: execute (AmServer.cpp:267): body: `v=0
o=- 0 0 IN IP4 128.131.80.218
s=session
c=IN IP4 128.131.80.218
b=CT:1000
t=0 0
m=audio 33678 RTP/AVP 97 111 112 6 0 8 4 5 3 101
k=base64:qNIq14h/mD0Jkylif9937MdxeXqvCF2SRHLus9Pl03o
a=rtpmap:97 red/8000
a=rtpmap:111 SIREN/16000
a=fmtp:111 bitrate=16000
a=rtpmap:112 G7221/16000
a=fmtp:112 bitrate=24000
a=rtpmap:6 DVI4/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=encryption:optional
'
(7522) DEBUG: execute (AmServer.cpp:278): everything is OK !
(7522) DEBUG: onInvite (AnswerMachine.cpp:92): email address for user
'testuser': darilion(a)ict.tuwien.ac.at
(7521) DEBUG: sig_usr (ans_machine.cpp:71): signal 17 received
[root@obelix answer_machine]#
---------------------------------------------------------------------------
- -------------
ser log (only the part with the request) (there was also REGISTER request
in between)
---------------------------------------------------------------------------
- -------------
0(7525) SIP Request:
0(7525) method: <INVITE>
0(7525) uri: <sip:testuser@obelix.ict.tuwien.ac.at>
0(7525) version: <SIP/2.0>
0(7525) parse_headers: flags=1
0(7525) end of header reached, state=5
0(7525) parse_headers: Via found, flags=1
0(7525) parse_headers: this is the first via
0(7525) After parse_msg...
0(7525) preparing to run routing scripts...
0(7525) lookup(): 'testuser' Not found in usrloc
0(7525) DEBUG : is_maxfwd_present: searching for max_forwards header
0(7525) parse_headers: flags=128
0(7525) DEBUG: is_maxfwd_present: value = 70
0(7525) parse_headers: flags=8
0(7525) DEBUG: add_param: tag=4f793f7afa1a4824976ad05508a58f6e
0(7525) DEBUG: add_param: epid=88cfc9fc75
0(7525) end of header reached, state=29
0(7525) parse_headers: flags=256
0(7525) end of header reached, state=9
0(7525) DEBUG: get_hdr_field: <To> [40];
uri=[sip:testuser@obelix.ict.tuwien.ac.at]
0(7525) DEBUG: to body [<sip:testuser@obelix.ict.tuwien.ac.at>
]
0(7525) get_hdr_field: cseq <CSeq>: <1> <INVITE>
0(7525) DEBUG: get_hdr_body : content_length=527
0(7525) found end of header
0(7525) find_first_route(): No Route headers found
0(7525) loose_route(): There is no Route HF
0(7525) check_self - checking if host==us: 23==14 &&
[obelix.ict.tuwien.ac.at] == [128.131.80.136]
0(7525) DEBUG: t_addifnew: msg id=1 , global msg id=0 , T on
entrance=0xffffffff
0(7525) parse_headers: flags=-1
0(7525) parse_headers: flags=60
0(7525) t_lookup_request: start searching: hash=64319, isACK=0
0(7525) DEBUG: proceeding to pre-RFC3261 transaction matching
0(7525) DEBUG: t_lookup_request: no transaction found
0(7525) DEBUG: t_check: msg id=1 global id=1 T start=0x422b90f0
0(7525) DEBUG: t_check: T alredy found!
0(7525) parse_headers: flags=-1
0(7525) check_via_address(128.131.80.218, 128.131.80.218, 0)
0(7525) DEBUG: reply sent out. buf=0x80b9020: SIP/2.0 1...,
shmem=0x422ba1d8: SIP/2.0 1
0(7525) DEBUG: t_reply: finished
0(7525) **************** vm start - begin ******************
0(7525) parse_headers: flags=-1
0(7525) DEBUG: t_check: msg id=1 global id=1 T start=0x422b90f0
0(7525) DEBUG: t_check: T alredy found!
0(7525) DEBUG: vm: ******* contacts: *******
0(7525) DEBUG: sip:128.131.80.218:6997
0(7525) DEBUG: vm: **** end of contacts ****
0(7525) vm: calculated route:
0(7525) vm: next r-uri: sip:128.131.80.218:6997
0(7525) parse_headers: flags=-1
0(7525) vm: write_to_vm_fifo: <0.2
sip_request.voicemail
INVITE
testuser
darilion(a)ict.tuwien.ac.at
obelix.ict.tuwien.ac.at
128.131.80.136
5060
sip:testuser@obelix.ict.tuwien.ac.at
sip:128.131.80.218:6997
"testuser2(a)obelix.ict.tuwien.ac.at"
<sip:testuser2@obelix.ict.tuwien.ac.at>
<sip:testuser@obelix.ict.tuwien.ac.at>
c88b8a17f734476d9fe80a16c44dd43c(a)128.131.80.218
4f793f7afa1a4824976ad05508a58f6e
.
1
64319:207254857
.
.
.
v=0
o=- 0 0 IN IP4 128.131.80.218
s=session
c=IN IP4 128.131.80.218
b=CT:1000
t=0 0
m=audio 33678 RTP/AVP 97 111 112 6 0 8 4 5 3 101
k=base64:qNIq14h/mD0Jkylif9937MdxeXqvCF2SRHLus9Pl03o
a=rtpmap:97 red/8000
a=rtpmap:111 SIREN/16000
a=fmtp:111 bitrate=16000
a=rtpmap:112 G7221/16000
a=fmtp:112 bitrate=24000
a=rtpmap:6 DVI4/16000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:5 DVI4/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=encryption:optional
0(7525) DEBUG: write_to_vm_fifo: write completed
0(7525) DEBUG: add_to_tail_of_timer[0]: 0x422b923c
0(7525) **************** vm start - end ******************
0(7525) receive_msg: cleaning up
0(7525) SIP Request:
0(7525) method: <REGISTER>
0(7525) uri: <sip:obelix.ict.tuwien.ac.at>
0(7525) version: <SIP/2.0>
0(7525) parse_headers: flags=1
0(7525) end of header reached, state=5
0(7525) parse_headers: Via found, flags=1
0(7525) parse_headers: this is the first via
0(7525) After parse_msg...
0(7525) preparing to run routing scripts...
0(7525) lookup(): '' Not found in usrloc
0(7525) DEBUG : is_maxfwd_present: searching for max_forwards header
0(7525) parse_headers: flags=128
0(7525) end of header reached, state=9
0(7525) DEBUG: get_hdr_field: <To> [37];
uri=[sip:kurth@obelix.ict.tuwien.ac.at]
0(7525) DEBUG: to body [<sip:kurth@obelix.ict.tuwien.ac.at>
]
0(7525) get_hdr_field: cseq <CSeq>: <1> <REGISTER>
0(7525) DEBUG: get_hdr_body : content_length=0
0(7525) found end of header
0(7525) DEBUG: is_maxfwd_present: max_forwards header not found!
0(7525) DEBUG: add_param: tag=0422f6c4-5608-4e5f-9526-79275e6d920e
0(7525) end of header reached, state=29
0(7525) parse_headers: flags=256
0(7525) find_first_route(): No Route headers found
0(7525) loose_route(): There is no Route HF
0(7525) parse_headers: flags=-1
0(7525) check_via_address(128.131.80.37, 128.131.80.37, 0)
0(7525) receive_msg: cleaning up
1(7527) DEBUG: timer routine:0,tl=0x422b923c next=(nil)
1(7527) DEBUG: FR_handler:stop retr. and send CANCEL (0x422b90f0)
1(7527) ->>>>>>>>> T_code=100, new_code=408
1(7527) DEBUG: relay_reply: branch=0, save=0, relay=0
1(7527) parse_headers: flags=-1
1(7527) check_via_address(128.131.80.218, 128.131.80.218, 0)
1(7527) DEBUG: reply relayed. buf=0x80b8778: SIP/2.0 4...,
shmem=0x422ba1d8: SIP/2.0 4
1(7527) DEBUG: add_to_tail_of_timer[4]: 0x422b91a8
1(7527) DEBUG: add_to_tail_of_timer[0]: 0x422b91c0
1(7527) DEBUG: final_response_handler : done
0(7525) SIP Request:
0(7525) method: <ACK>
0(7525) uri: <sip:testuser@obelix.ict.tuwien.ac.at>
0(7525) version: <SIP/2.0>
0(7525) parse_headers: flags=1
0(7525) end of header reached, state=5
0(7525) parse_headers: Via found, flags=1
0(7525) parse_headers: this is the first via
0(7525) After parse_msg...
0(7525) DEBUG : sl_filter_ACK: to late to be a local ACK!
0(7525) preparing to run routing scripts...
0(7525) lookup(): 'testuser' Not found in usrloc
0(7525) DEBUG : is_maxfwd_present: searching for max_forwards header
0(7525) parse_headers: flags=128
0(7525) DEBUG: is_maxfwd_present: value = 70
0(7525) parse_headers: flags=8
0(7525) DEBUG: add_param: tag=4f793f7afa1a4824976ad05508a58f6e
0(7525) DEBUG: add_param: epid=88cfc9fc75
0(7525) end of header reached, state=29
0(7525) parse_headers: flags=256
0(7525) DEBUG: add_param: tag=58ff021f53ab5af6efbf188c434a3c48-8561
0(7525) end of header reached, state=29
0(7525) DEBUG: get_hdr_field: <To> [82];
uri=[sip:testuser@obelix.ict.tuwien.ac.at]
0(7525) DEBUG: to body [<sip:testuser@obelix.ict.tuwien.ac.at>]
0(7525) get_hdr_field: cseq <CSeq>: <1> <ACK>
0(7525) DEBUG: get_hdr_body : content_length=0
0(7525) found end of header
0(7525) find_first_route(): No Route headers found
0(7525) loose_route(): There is no Route HF
0(7525) check_self - checking if host==us: 23==14 &&
[obelix.ict.tuwien.ac.at] == [128.131.80.136]
0(7525) DEBUG: t_addifnew: msg id=3 , global msg id=1 , T on
entrance=0xffffffff
0(7525) parse_headers: flags=-1
0(7525) parse_headers: flags=60
0(7525) t_lookup_request: start searching: hash=64319, isACK=1
0(7525) DEBUG: proceeding to pre-RFC3261 transaction matching
0(7525) DEBUG: non-2xx ACK matched
0(7525) DEBUG: t_lookup_request: transaction found (T=0x422b90f0)
0(7525) DEBUG: cleanup_uacs: RETR/FR timers reset
0(7525) DEBUG: add_to_tail_of_timer[2]: 0x422b9138
0(7525) receive_msg: cleaning up
1(7527) DEBUG: timer routine:0,tl=0x422b91c0 next=(nil)
1(7527) DEBUG: timer routine:4,tl=0x422b91a8 next=(nil)
1(7527) DEBUG: timer routine:2,tl=0x422b9138 next=(nil)
1(7527) DEBUG: wait_handler : removing 0x422b90f0 from table
1(7527) DEBUG: delete transaction 0x422b90f0
1(7527) DEBUG: wait_handler : done
7792
days inactive
7794
days old
1 comments
2 participants
participants (2)
-
Klaus Darilion -
Ulrich Abend