RE: не регистрируется на sip шлюзе

Если прописать в маршрутизаторе строчку: N:S:<хххххх@94.180.199.34> = 101@kseon.ru Звонок приходит на 101:
1. если зарегистрирован айбим (сам айбим конечно не звонит) 2. если использовать регистрацию непосредственно CGP звонок также приходит Лог звонка:

17:19:52.812 5 SIP [0.0.0.0]:5060 <- [88.87.64.228]:5060 inp(801): INVITE sip:xxxxxx@94.180.199.34 SIP/2.0\r\nVia: SIP/2.0/UDP 88.87.64.228;branch=88
17:19:52.812 2 SIPDATA-007702 inp: req [0.0.0.0]:5060 <- udp[88.87.64.228]:5060 INVITE(801 bytes) sip:xxxxxx@94.180.199.34
17:19:52.812 5 SIPDATA-007702 inp: INVITE sip:xxxxxx@94.180.199.34 SIP/2.0
17:19:52.812 5 SIPDATA-007702 inp: Via: SIP/2.0/UDP 88.87.64.228;branch=88.87.64.225.5075-95170000
17:19:52.812 5 SIPDATA-007702 inp: From: "AlterPSS" <sip:89033xxxxxx@88.87.64.225:5075>;tag=88.87.64.225.5075-127790288-16839
17:19:52.812 5 SIPDATA-007702 inp: To: <sip:xxxxxx@94.180.199.34>
17:19:52.812 5 SIPDATA-007702 inp: User-Agent: AlterProxySoftSwitch
17:19:52.812 5 SIPDATA-007702 inp: Call-ID: 7D643027-000b6965-79decd0-001fdc@88.87.64.225
17:19:52.812 5 SIPDATA-007702 inp: CSeq: 6037 INVITE
17:19:52.812 5 SIPDATA-007702 inp: Contact: <sip:89033xxxxxx@88.87.64.228>
17:19:52.812 5 SIPDATA-007702 inp: Content-Type: application/sdp
17:19:52.812 5 SIPDATA-007702 inp: Content-Length: 282
17:19:52.812 5 SIPDATA-007702 inp: Date: Fri, 30 Jan 2009 14:18:37 GMT
17:19:52.812 5 SIPDATA-007702 inp: Allow: INVITE, ACK, CANCEL, OPTIONS, INFO, BYE
17:19:52.812 5 SIPDATA-007702 inp: 
17:19:52.812 5 SIPDATA-007702 inp: v=0
17:19:52.812 5 SIPDATA-007702 inp: o=88.87.64.225 1233325117 1233325117 IN IP4 88.87.64.225
17:19:52.812 5 SIPDATA-007702 inp: s=AlterProxySoftSwitch
17:19:52.812 5 SIPDATA-007702 inp: c=IN IP4 88.87.64.225
17:19:52.812 5 SIPDATA-007702 inp: t=0 0
17:19:52.812 5 SIPDATA-007702 inp: m=audio 16464 RTP/AVP 18 8 0 101
17:19:52.812 5 SIPDATA-007702 inp: a=ptime:20
17:19:52.812 5 SIPDATA-007702 inp: a=rtpmap:18 G729/8000
17:19:52.812 5 SIPDATA-007702 inp: a=rtpmap:8 PCMA/8000
17:19:52.812 5 SIPDATA-007702 inp: a=rtpmap:0 PCMU/8000
17:19:52.812 5 SIPDATA-007702 inp: a=rtpmap:101 telephone-event/8000
17:19:52.812 5 SIPDATA-007702 inp: a=fmtp:101 0-15
17:19:52.812 4 SIPDATA-007702 Hash=1234381262
17:19:52.812 4 SIPS-002422 enqueued
17:19:52.812 5 SIPS-002422 INITIAL posted
17:19:52.812 2 SIPDATA-007702 created SIPS-002422
17:19:52.812 5 SIPS(2) 002422: processing INITIAL
17:19:52.812 2 SIPS-002422 [007702] INVITE sip:xxxxxx@94.180.199.34 from udp[88.87.64.228]:5060
17:19:52.812 2 SIPDATA-007703 out: rsp [0.0.0.0]:5060 -> udp[88.87.64.228]:5060 100-INVITE(336 bytes)
17:19:52.812 5 SIPDATA-007703 out: SIP/2.0 100 Trying
17:19:52.812 5 SIPDATA-007703 out: Via: SIP/2.0/UDP 88.87.64.228;branch=88.87.64.225.5075-95170000
17:19:52.812 5 SIPDATA-007703 out: From: "AlterPSS" <sip:89033xxxxxx@88.87.64.225:5075>;tag=88.87.64.225.5075-127790288-16839
17:19:52.812 5 SIPDATA-007703 out: To: <sip:xxxxxx@94.180.199.34>
17:19:52.812 5 SIPDATA-007703 out: Call-ID: 7D643027-000b6965-79decd0-001fdc@88.87.64.225
17:19:52.812 5 SIPDATA-007703 out: CSeq: 6037 INVITE
17:19:52.812 5 SIPDATA-007703 out: Server: CommuniGatePro/5.2.12
17:19:52.812 5 SIPDATA-007703 out: Content-Length: 0
17:19:52.812 5 SIPDATA-007703 out: 
17:19:52.812 5 SIP [0.0.0.0]:5060 -> [88.87.64.228]:5060 out(336): SIP/2.0 100 Trying\r\nVia: SIP/2.0/UDP 88.87.64.228;branch=88.87.64.225.5075-95170
17:19:52.812 2 SIPS-002422 [007703] 100-INVITE(trying) sent [0.0.0.0]:5060 -> udp[88.87.64.228]:5060
17:19:52.812 2 SIPS-002422 created SIGNAL-004898
17:19:52.812 5 SIPS(2) 002422: idling
17:19:52.812 2 SIGNAL-004898 SIPS-002422: INVITE sip:xxxxxx@94.180.199.34
17:19:52.812 2 ROUTER SMTP: '89033xxxxxx@88.87.64.225' accepted: '89033xxxxxx' at '[88.87.64.225]'
17:19:52.812 2 SIGNAL-004898 INVITE sip:xxxxxx@94.180.199.34 via sip:xxxxxx@94.180.199.34
17:19:52.812 2 ROUTER LOCAL: 'xxxxxx@94.180.199.34' accepted: '' at 'kovalev_em'
17:19:52.812 2 DIALOG-000044 created(authInp)
17:19:52.812 2 DIALOG-000044 callee set: kovalev_em@kseon.ru
17:19:52.812 2 SIGNALRULE-004898 rule(#MailOnTimer): redirected
17:19:52.812 2 SIGNAL-004898 redirected by Rules
17:19:52.812 2 SIGNAL-004898 INVITE sip:voicemail{noanswer}#kovalev_em@kseon.ru via sip:voicemail{noanswer}#kovalev_em@kseon.ru
17:19:52.812 2 ROUTER LOCAL: 'voicemail{noanswer}#kovalev_em@kseon.ru' accepted: '#voicemail{noanswer}' at 'kovalev_em'
17:19:52.812 4 PBXLEG-001540 enqueued
17:19:52.812 2 PBXLEG-001540 'voicemail' created for kovalev_em@kseon.ru
17:19:52.812 5 PBXLEG-001540 REQUEST posted
17:19:52.812 2 SIGNAL-004898 {2} sent to NODE-001540: INVITE sip:voicemail{noanswer}#kovalev_em@kseon.ru
17:19:52.812 5 NODE(4) 001540: processing REQUEST
17:19:52.812 4 PBXLEG-001540 INVITE request received
17:19:52.812 2 DIALOG-000045 created as DIALOG-000044 copy(copy)
17:19:52.812 2 DIALOG-000045 enqueued
17:19:52.812 2 PBXLEG-001540 DIALOG-000045(inp) started with 89033xxxxxx@88.87.64.225(sip:89033xxxxxx@88.87.64.228)
17:19:52.812 2 PBXLEG-001540 session refresh=300(active)
17:19:52.812 4 PBXLEG-001540 remote SDP set: peer
17:19:52.812 5 PBXLEG-001540 leg state=8
17:19:52.812 4 PBXLEG-001540 signalling completed(init)
17:19:52.812 5 PBXLEG-001540 pbx state=1(inited), active
17:19:52.812 2 PBXLEG-001540 voicemail.sppr(Main) started
17:19:52.812 2 ROUTER SMTP: '89033xxxxxx@88.87.64.225' accepted: '89033xxxxxx' at '[88.87.64.225]'
17:19:52.812 2 MEDIA-000007 created(4444444B) for PBXLEG-001540, audio port [0.0.0.0]:60000
17:19:52.812 2 MEDIA-000007 set:[88.87.64.225]:16464 SDP(101=DTMF 18=G729/8000,8=PCMA/8000,0=PCMU/8000)sendrecv <-> (PCMA/8000)sendrecv
17:19:52.812 4 PBXLEG-001540 MEDIA-000007 created
17:19:52.812 4 PBXLEG-001540 session timer refreshed
17:19:52.812 2 MEDIA-000007 local SDP generated
17:19:52.812 4 PBXLEG-001540 local SDP: local media
17:19:52.812 2 SIGNAL-004898 200 received from NODE-001540
17:19:52.812 2 DIALOG-000045 setting 'To' tag: 000000000001540-B61F1DFB-C9B7FEEE
17:19:52.812 2 SIGNAL-004898 updating DIALOG-000045 on 200-response
17:19:52.812 2 DIALOG-000045 connected
17:19:52.812 2 ACCOUNT(kovalev_em) inp call connected. alerting=0 sec
17:19:52.812 2 SIGNAL-004898 200 relaying
17:19:52.812 5 SIPS-002422 FINAL posted
17:19:52.812 2 SIGNAL-004898 releasing
17:19:52.812 5 SIPS(3) 002422: processing FINAL
17:19:52.812 2 SIPDATA-007704 out: rsp [0.0.0.0]:5060 -> udp[88.87.64.228]:5060 200-INVITE(977 bytes)
17:19:52.812 5 SIPDATA-007704 out: SIP/2.0 200 OK
17:19:52.812 5 SIPDATA-007704 out: Via: SIP/2.0/UDP 88.87.64.228;branch=88.87.64.225.5075-95170000
17:19:52.812 5 SIPDATA-007704 out: Record-Route: <sip:45.dialog.cgatepro;lr>
17:19:52.812 5 SIPDATA-007704 out: Record-Route: <sip:94.180.199.34:5060;lr>
17:19:52.812 5 SIPDATA-007704 out: From: "AlterPSS" <sip:89033xxxxxx@88.87.64.225:5075>;tag=88.87.64.225.5075-127790288-16839
17:19:52.812 5 SIPDATA-007704 out: To: <sip:xxxxxx@94.180.199.34>;tag=000000000001540-B61F1DFB-C9B7FEEE
17:19:52.812 5 SIPDATA-007704 out: Call-ID: 7D643027-000b6965-79decd0-001fdc@88.87.64.225
17:19:52.812 5 SIPDATA-007704 out: CSeq: 6037 INVITE
17:19:52.812 5 SIPDATA-007704 out: Contact: <sip:signode-1540-C9B7FEEE@94.180.199.34>
17:19:52.812 5 SIPDATA-007704 out: Supported: 100rel,timer,replaces,histinfo
17:19:52.812 5 SIPDATA-007704 out: User-Agent: CommuniGatePro-callLeg/5.2.12
17:19:52.812 5 SIPDATA-007704 out: Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,REFER
17:19:52.812 5 SIPDATA-007704 out: Content-Type: application/sdp
17:19:52.812 5 SIPDATA-007704 out: Content-Length: 301
17:19:52.812 5 SIPDATA-007704 out: 
17:19:52.812 5 SIPDATA-007704 out: v=0
17:19:52.812 5 SIPDATA-007704 out: o=CGPLeg001540 4254712639 2127356320 IN IP4 94.180.199.34
17:19:52.812 5 SIPDATA-007704 out: s=-
17:19:52.812 5 SIPDATA-007704 out: c=IN IP4 94.180.199.34
17:19:52.812 5 SIPDATA-007704 out: t=0 0
17:19:52.812 5 SIPDATA-007704 out: m=audio 60000 RTP/AVP 8 0 101
17:19:52.812 5 SIPDATA-007704 out: c=IN IP4 94.180.199.34
17:19:52.812 5 SIPDATA-007704 out: a=rtpmap:8 PCMA/8000
17:19:52.812 5 SIPDATA-007704 out: a=rtpmap:0 PCMU/8000
17:19:52.812 5 SIPDATA-007704 out: a=rtpmap:101 telephone-event/8000
17:19:52.812 5 SIPDATA-007704 out: a=fmtp:101 0-16,32-49,64-89
17:19:52.812 5 SIPDATA-007704 out: a=sendrecv
17:19:52.812 5 SIPDATA-007704 out: a=rtcpping:M:7:470937555
17:19:52.812 5 SIP [0.0.0.0]:5060 -> [88.87.64.228]:5060 out(977): SIP/2.0 200 OK\r\nVia: SIP/2.0/UDP 88.87.64.228;branch=88.87.64.225.5075-95170000\r
17:19:52.812 2 SIPS-002422 [007704] 200-INVITE(final) sent [0.0.0.0]:5060 -> udp[88.87.64.228]:5060
17:19:52.812 5 SIPS-002422 timeout set for 32 secs
17:19:52.812 5 SIPS(3) 002422: enqueued (32 secs)
17:19:52.812 5 PBXLEG-001540 leg state=10
17:19:52.812 5 PBXLEG-001540 timeout set for 3 secs
17:19:52.812 4 PBXLEG-001540 unbinding DIALOG-000045
17:19:52.812 2 PBXLEG-001540 accepting started
17:19:52.812 5 PBXLEG-001540 pbx state=2(accepting), timeout=3300s
17:19:52.812 5 NODE(4) 001540: enqueued (3 secs)
17:19:52.812 2 MEDIA-000007 processor started
17:19:52.812 5 SIP [0.0.0.0]:5060 <- [88.87.64.228]:5060 inp(486): ACK sip:signode-1540-C9B7FEEE@94.180.199.34 SIP/2.0\r\nVia: SIP/2.0/UDP 88.87.64.2
17:19:52.812 2 SIPDATA-007705 inp: req [0.0.0.0]:5060 <- udp[88.87.64.228]:5060 ACK(486 bytes) sip:signode-1540-C9B7FEEE@94.180.199.34
17:19:52.812 5 SIPDATA-007705 inp: ACK sip:signode-1540-C9B7FEEE@94.180.199.34 SIP/2.0
17:19:52.812 5 SIPDATA-007705 inp: Via: SIP/2.0/UDP 88.87.64.228;branch=88.87.64.225.5075-95170000
17:19:52.812 5 SIPDATA-007705 inp: Route: <sip:94.180.199.34:5060;lr>
17:19:52.812 5 SIPDATA-007705 inp: Route: <sip:45.dialog.cgatepro;lr>
17:19:52.812 5 SIPDATA-007705 inp: From: "AlterPSS" <sip:89033xxxxxx@88.87.64.225:5075>;tag=88.87.64.225.5075-127790288-16839
17:19:52.812 5 SIPDATA-007705 inp: To: <sip:xxxxxx@94.180.199.34>;tag=000000000001540-B61F1DFB-C9B7FEEE
17:19:52.812 5 SIPDATA-007705 inp: Call-ID: 7D643027-000b6965-79decd0-001fdc@88.87.64.225
17:19:52.812 5 SIPDATA-007705 inp: CSeq: 6037 ACK
17:19:52.812 5 SIPDATA-007705 inp: Contact: <sip:89033xxxxxx@88.87.64.228>
17:19:52.812 5 SIPDATA-007705 inp: Content-Length: 0
17:19:52.812 5 SIPDATA-007705 inp: 
17:19:52.812 4 SIPDATA-007705 Hash=1234381262
17:19:52.812 4 SIPDATA-007705 self-route removed: <sip:94.180.199.34:5060;lr>
17:19:52.812 4 SIPDATA-007705 special route detected: sip:45.dialog.cgatepro;lr
17:19:52.812 5 SIPS-002422 SUPPL posted
17:19:52.812 2 SIPDATA-007705 sent to SIPS-002422
17:19:52.812 5 SIPS(1) 002422: processing SUPPL
17:19:52.812 2 SIPS-002422 [007705] ACK for 2xx-INVITE
17:19:52.812 5 PBXLEG-001540 DOWNSTREAM posted
17:19:52.812 5 SIPS(1) 002422: enqueued (32 secs)
17:19:52.812 5 NODE(5) 001540: processing DOWNSTREAM
17:19:52.812 4 PBXLEG-001540 INVITE response ACK'ed
17:19:52.812 5 PBXLEG-001540 leg state=0
17:19:52.812 5 PBXLEG-001540 timeout set for 3300 secs
17:19:52.812 4 PBXLEG-001540 signalling completed
17:19:52.812 5 PBXLEG-001540 pbx state=1(signal completed), active
17:19:52.812 5 PBXLEG-001540 timeout cleared
17:19:52.812 2 PBXLEG-001540 QUEUE[MissedActive]: enqueue
17:19:52.812 4 PBXLEG-001540 playing(loop) Blank(558 bytes)
17:19:52.812 5 PBXLEG-001540 pbx state=3(playing media), timeout=3600s
17:19:52.812 4 MEDIA-000007 audio loaded 512 samples, 8000/sec, wakeup
17:19:52.812 4 MEDIA-000007 loop=1500msec, offset=0msec
17:19:52.812 5 PBXLEG-001540 timeout set for 150 secs
17:19:52.812 5 NODE(5) 001540: enqueued (150 secs)
17:19:52.890 5 MEDIA-000007 inp:(1,4623ABF8)3D0C6962(8):(160) E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5 E5
17:19:52.890 2 MEDIA-000007 inp:(1,4623ABF8)3D0C6962 sourced

17:20:00.765 5 MEDIA-000007 filled: 75
17:20:00.812 5 SIP [0.0.0.0]:5060 <- [88.87.64.228]:5060 inp(482): BYE sip:signode-1540-C9B7FEEE@94.180.199.34 SIP/2.0\r\nVia: SIP/2.0/UDP 88.87.64.2
17:20:00.812 2 SIPDATA-007707 inp: req [0.0.0.0]:5060 <- udp[88.87.64.228]:5060 BYE(482 bytes) sip:signode-1540-C9B7FEEE@94.180.199.34
17:20:00.812 5 SIPDATA-007707 inp: BYE sip:signode-1540-C9B7FEEE@94.180.199.34 SIP/2.0
17:20:00.812 5 SIPDATA-007707 inp: Via: SIP/2.0/UDP 88.87.64.228;branch=88.87.64.225.5075-96170000
17:20:00.812 5 SIPDATA-007707 inp: Route: <sip:94.180.199.34:5060;lr>
17:20:00.812 5 SIPDATA-007707 inp: Route: <sip:45.dialog.cgatepro;lr>
17:20:00.812 5 SIPDATA-007707 inp: From: "AlterPSS" <sip:89033xxxxxx@88.87.64.225:5075>;tag=88.87.64.225.5075-127790288-16839
17:20:00.812 5 SIPDATA-007707 inp: To: <sip:xxxxxx@94.180.199.34>;tag=000000000001540-B61F1DFB-C9B7FEEE
17:20:00.812 5 SIPDATA-007707 inp: Call-ID: 7D643027-000b6965-79decd0-001fdc@88.87.64.225
17:20:00.812 5 SIPDATA-007707 inp: CSeq: 6038 BYE
17:20:00.812 5 SIPDATA-007707 inp: Content-Length: 0
17:20:00.812 5 SIPDATA-007707 inp: Date: Fri, 30 Jan 2009 14:18:45 GMT
17:20:00.812 5 SIPDATA-007707 inp: 
17:20:00.812 4 SIPDATA-007707 Hash=1245609987
17:20:00.812 4 SIPDATA-007707 self-route removed: <sip:94.180.199.34:5060;lr>
17:20:00.812 4 SIPDATA-007707 special route detected: sip:45.dialog.cgatepro;lr
17:20:00.812 4 SIPS-002424 enqueued
17:20:00.812 5 SIPS-002424 INITIAL posted
17:20:00.812 2 SIPDATA-007707 created SIPS-002424
17:20:00.812 5 SIPS(2) 002424: processing INITIAL
17:20:00.812 2 SIPS-002424 [007707] BYE sip:signode-1540-C9B7FEEE@94.180.199.34 from udp[88.87.64.228]:5060
17:20:00.812 2 SIPS-002424 created SIGNAL-004900
17:20:00.812 5 SIPS(2) 002424: idling
17:20:00.812 2 SIGNAL-004900 SIPS-002424: BYE sip:signode-1540-C9B7FEEE@94.180.199.34
17:20:00.812 2 DIALOG-000045 signal expiration updated
17:20:00.812 2 SIGNAL-004900 BYE sip:signode-1540-C9B7FEEE@94.180.199.34 via sip:signode-1540-C9B7FEEE@94.180.199.34
17:20:00.812 5 PBXLEG-001540 REQUEST posted
17:20:00.812 2 SIGNAL-004900 {1} sent to NODE-001540: BYE sip:signode-1540-C9B7FEEE@94.180.199.34
17:20:00.812 5 NODE(6) 001540: processing REQUEST
17:20:00.812 4 PBXLEG-001540 BYE request received
17:20:00.812 4 PBXLEG-001540 BYE received
17:20:00.812 5 PBXLEG-001540 leg state=16
17:20:00.812 5 PBXLEG-001540 timeout set for 3599 secs
17:20:00.812 2 PBXLEG-001540 disconnected
17:20:00.812 4 MEDIA-000007 interrupted
17:20:00.812 4 PBXLEG-001540 Event([disconnect]) enqueued
17:20:00.812 4 MEDIA-000007 interrupted
17:20:00.812 5 PBXLEG-001540 timeout set for 3 secs
17:20:00.812 5 NODE(6) 001540: enqueued (3 secs)
17:20:00.812 2 SIGNAL-004900 200 received from NODE-001540
17:20:00.812 2 ACCOUNT(kovalev_em) inp call completed. duration=8 sec
17:20:00.812 2 DIALOG-000045 dequeued(disconnected)
17:20:00.812 2 SIGNAL-004900 200 relaying
17:20:00.812 5 SIPS-002424 FINAL posted
17:20:00.812 5 SIPS(3) 002424: processing FINAL
17:20:00.812 2 SIPDATA-007708 out: rsp [0.0.0.0]:5060 -> udp[88.87.64.228]:5060 200-BYE(367 bytes)
17:20:00.812 5 SIPDATA-007708 out: SIP/2.0 200 OK
17:20:00.812 5 SIPDATA-007708 out: Via: SIP/2.0/UDP 88.87.64.228;branch=88.87.64.225.5075-96170000
17:20:00.812 5 SIPDATA-007708 out: From: "AlterPSS" <sip:89033xxxxxx@88.87.64.225:5075>;tag=88.87.64.225.5075-127790288-16839
17:20:00.812 5 SIPDATA-007708 out: To: <sip:xxxxxx@94.180.199.34>;tag=000000000001540-B61F1DFB-C9B7FEEE
17:20:00.812 5 SIPDATA-007708 out: Call-ID: 7D643027-000b6965-79decd0-001fdc@88.87.64.225
17:20:00.812 5 SIPDATA-007708 out: CSeq: 6038 BYE
17:20:00.812 5 SIPDATA-007708 out: Server: CommuniGatePro/5.2.12
17:20:00.812 5 SIPDATA-007708 out: Content-Length: 0
17:20:00.812 5 SIPDATA-007708 out: 
17:20:00.812 5 SIP [0.0.0.0]:5060 -> [88.87.64.228]:5060 out(367): SIP/2.0 200 OK\r\nVia: SIP/2.0/UDP 88.87.64.228;branch=88.87.64.225.5075-96170000\r
17:20:00.812 2 SIPS-002424 [007708] 200-BYE(final) sent [0.0.0.0]:5060 -> udp[88.87.64.228]:5060
17:20:00.812 4 SIPS-002424 completed
17:20:00.812 5 SIPS-002424 timeout set for 32 secs
17:20:00.812 5 SIPS(3) 002424: enqueued (32 secs)
17:20:00.812 2 SIGNAL-004900 releasing
17:20:00.812 5 MEDIA-000007 read time-out
17:20:00.812 4 MEDIA-000007 reporting end of playing
17:20:00.812 5 PBXLEG-001540 PlayingDone posted
17:20:00.812 4 MEDIA-000007 playing ended
17:20:00.812 5 MEDIA-000007 filled: 48
17:20:00.812 5 NODE(4) 001540: processing PlayingDone
17:20:00.812 5 PBXLEG-001540 pbx state=1(playing completed), active
17:20:00.812 5 PBXLEG-001540 timeout cleared
17:20:00.812 4 PBXLEG-001540 Event([disconnect]) retrieved
17:20:00.812 2 MEDIA-000007 remote SDP retrieved
17:20:00.812 4 PBXLEG-001540 MEDIA-000007 released
17:20:00.812 4 MEDIA-000007 closing
17:20:00.921 2 MEDIA-000007 processor finished
17:20:01.015 2 MEDIA-000007 released
17:20:01.015 2 PBXLEG-001540 QUEUE[MissedActive]: dequeue
17:20:01.015 2 PBXLEG-001540 program stopped
17:20:01.015 4 PBXLEG-001540 leg finishing
17:20:01.015 5 PBXLEG-001540 timeout immediate set
17:20:01.015 5 NODE(4) 001540: killing
17:20:01.015 4 PBXLEG-001540 dequeued
17:20:01.015 2 PBXLEG-001540 releasing