[2012-01-09 09:31:44] Asterisk Ready. [2012-01-09 09:31:44] == Parsing '/etc/asterisk/cli.conf': [2012-01-09 09:31:44] == Found [2012-01-09 09:31:46] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:29] == Using UDPTL TOS bits 184 [2012-01-09 09:32:29] == Using UDPTL CoS mark 5 [2012-01-09 09:32:29] == Using SIP RTP TOS bits 184 [2012-01-09 09:32:29] == Using SIP RTP CoS mark 5 [2012-01-09 09:32:29] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:30] -- Executing [*830@default-super:1] Answer("SIP/6057-00000000", "") in new stack [2012-01-09 09:32:30] -- Executing [*830@default-super:2] Set("SIP/6057-00000000", "GROUP(OUTGOING)=6057") in new stack [2012-01-09 09:32:30] -- Executing [*830@default-super:3] Set("SIP/6057-00000000", "__PAGING_RINGTIME=5") in new stack [2012-01-09 09:32:30] -- Executing [*830@default-super:4] NoCDR("SIP/6057-00000000", "") in new stack [2012-01-09 09:32:30] -- Executing [*830@default-super:5] Set("SIP/6057-00000000", "TIMEOUT(absolute)=120") in new stack [2012-01-09 09:32:30] Channel will hangup at 2012-01-09 09:34:30.854 EST. [2012-01-09 09:32:30] -- Executing [*830@default-super:6] Goto("SIP/6057-00000000", "100") in new stack [2012-01-09 09:32:30] -- Goto (default-super,*830,100) [2012-01-09 09:32:30] -- Executing [*830@default-super:100] Page("SIP/6057-00000000", "Local/1004@default-local-paging&Local/6000@default-local-paging&Local/6001@default-local-paging&Local/6002@default-local-paging&Local/6003@default-local-paging&Local/6004@default-local-paging&Local/6005@default-local-paging&Local/6006@default-local-paging&Local/6007@default-local-paging&Local/6010@default-local-paging&Local/6057@default-local-paging&Local/6989@default-local-paging") in new stack [2012-01-09 09:32:31] -- Called 1004@default-local-paging [2012-01-09 09:32:31] -- Executing [1004@default-local-paging:1] NoCDR("Local/1004@default-local-paging-63b5;2", "") in new stack [2012-01-09 09:32:31] -- Executing [1004@default-local-paging:2] Set("Local/1004@default-local-paging-63b5;2", "LOCAL_EXTEN=1004") in new stack [2012-01-09 09:32:31] -- Executing [1004@default-local-paging:3] SIPAddHeader("Local/1004@default-local-paging-63b5;2", "Call-Info:\;answer-after=0") in new stack [2012-01-09 09:32:31] -- Called 6000@default-local-paging [2012-01-09 09:32:31] -- Executing [6000@default-local-paging:1] NoCDR("Local/6000@default-local-paging-658c;2", "") in new stack [2012-01-09 09:32:31] -- Executing [1004@default-local-paging:4] Set("Local/1004@default-local-paging-63b5;2", "LOCAL_CHANNEL=SIP/1004") in new stack [2012-01-09 09:32:31] -- Executing [1004@default-local-paging:5] Gosub("Local/1004@default-local-paging-63b5;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:31] -- Executing [6000@default-local-paging:2] Set("Local/6000@default-local-paging-658c;2", "LOCAL_EXTEN=6000") in new stack [2012-01-09 09:32:31] -- Called 6001@default-local-paging [2012-01-09 09:32:31] -- Executing [6001@default-local-paging:1] NoCDR("Local/6001@default-local-paging-85da;2", "") in new stack [2012-01-09 09:32:31] -- Executing [6000@default-local-paging:3] SIPAddHeader("Local/6000@default-local-paging-658c;2", ""Call-Info: sip:\;answer-after=0"") in new stack [2012-01-09 09:32:31] -- Executing [6001@default-local-paging:2] Set("Local/6001@default-local-paging-85da;2", "LOCAL_EXTEN=6001") in new stack [2012-01-09 09:32:31] -- Executing [paging@all-local-extension:1] Set("Local/1004@default-local-paging-63b5;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:31] -- Executing [6002@default-local-paging:1] NoCDR("Local/6002@default-local-paging-84a9;2", "") in new stack [2012-01-09 09:32:31] -- Called 6002@default-local-paging [2012-01-09 09:32:31] -- Executing [6002@default-local-paging:2] Set("Local/6002@default-local-paging-84a9;2", "LOCAL_EXTEN=6002") in new stack [2012-01-09 09:32:31] -- Executing [6000@default-local-paging:4] Set("Local/6000@default-local-paging-658c;2", "LOCAL_CHANNEL=SIP/6000") in new stack [2012-01-09 09:32:31] -- Executing [paging@all-local-extension:2] Set("Local/1004@default-local-paging-63b5;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:31] -- Executing [6002@default-local-paging:3] SIPAddHeader("Local/6002@default-local-paging-84a9;2", "Alert-Info: Auto Answer") in new stack [2012-01-09 09:32:31] -- Executing [6001@default-local-paging:3] SIPAddHeader("Local/6001@default-local-paging-85da;2", "Call-Info:\;answer-after=0") in new stack [2012-01-09 09:32:31] -- Executing [6000@default-local-paging:5] Gosub("Local/6000@default-local-paging-658c;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:31] -- Executing [paging@all-local-extension:1] Set("Local/6000@default-local-paging-658c;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:31] -- Executing [paging@all-local-extension:2] Set("Local/6000@default-local-paging-658c;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:31] -- Executing [paging@all-local-extension:3] GotoIf("Local/6000@default-local-paging-658c;2", "0?6") in new stack [2012-01-09 09:32:32] -- Executing [6003@default-local-paging:1] NoCDR("Local/6003@default-local-paging-364a;2", "") in new stack [2012-01-09 09:32:32] -- Called 6003@default-local-paging [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:4] Set("Local/6000@default-local-paging-658c;2", "OUTBOUND_GROUP=6000@INCOMING") in new stack [2012-01-09 09:32:32] -- Executing [6003@default-local-paging:2] Set("Local/6003@default-local-paging-364a;2", "LOCAL_EXTEN=6003") in new stack [2012-01-09 09:32:32] -- Executing [6002@default-local-paging:4] Set("Local/6002@default-local-paging-84a9;2", "LOCAL_CHANNEL=SIP/6002") in new stack [2012-01-09 09:32:32] -- Executing [6002@default-local-paging:5] Gosub("Local/6002@default-local-paging-84a9;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:1] Set("Local/6002@default-local-paging-84a9;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:32] -- Executing [6001@default-local-paging:4] Set("Local/6001@default-local-paging-85da;2", "LOCAL_CHANNEL=SIP/6001") in new stack [2012-01-09 09:32:32] -- Executing [6001@default-local-paging:5] Gosub("Local/6001@default-local-paging-85da;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:1] Set("Local/6001@default-local-paging-85da;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:2] Set("Local/6001@default-local-paging-85da;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:3] GotoIf("Local/1004@default-local-paging-63b5;2", "0?6") in new stack [2012-01-09 09:32:32] -- Called 6004@default-local-paging [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:2] Set("Local/6002@default-local-paging-84a9;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:32] -- Executing [6004@default-local-paging:1] NoCDR("Local/6004@default-local-paging-cb33;2", "") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:5] Dial("Local/6000@default-local-paging-658c;2", "SIP/6000,5") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:4] Set("Local/1004@default-local-paging-63b5;2", "OUTBOUND_GROUP=1004@INCOMING") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:5] Dial("Local/1004@default-local-paging-63b5;2", "SIP/1004,5") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:3] GotoIf("Local/6001@default-local-paging-85da;2", "0?6") in new stack [2012-01-09 09:32:32] -- Executing [6004@default-local-paging:2] Set("Local/6004@default-local-paging-cb33;2", "LOCAL_EXTEN=6004") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:3] GotoIf("Local/6002@default-local-paging-84a9;2", "0?6") in new stack [2012-01-09 09:32:32] -- Called 6005@default-local-paging [2012-01-09 09:32:32] -- Executing [6005@default-local-paging:1] NoCDR("Local/6005@default-local-paging-85a8;2", "") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:4] Set("Local/6002@default-local-paging-84a9;2", "OUTBOUND_GROUP=6002@INCOMING") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:4] Set("Local/6001@default-local-paging-85da;2", "OUTBOUND_GROUP=6001@INCOMING") in new stack [2012-01-09 09:32:32] == Using UDPTL TOS bits 184 [2012-01-09 09:32:32] -- Executing [6005@default-local-paging:2] Set("Local/6005@default-local-paging-85a8;2", "LOCAL_EXTEN=6005") in new stack [2012-01-09 09:32:32] == Using UDPTL CoS mark 5 [2012-01-09 09:32:32] -- Executing [6003@default-local-paging:3] SIPAddHeader("Local/6003@default-local-paging-364a;2", "Alert-Info: Auto Answer") in new stack [2012-01-09 09:32:32] == Using SIP RTP TOS bits 184 [2012-01-09 09:32:32] == Using SIP RTP CoS mark 5 [2012-01-09 09:32:32] -- Executing [6004@default-local-paging:3] SIPAddHeader("Local/6004@default-local-paging-cb33;2", "Call-Info:\;answer-after=0") in new stack [2012-01-09 09:32:32] -- Called 6006@default-local-paging [2012-01-09 09:32:32] -- Executing [6006@default-local-paging:1] NoCDR("Local/6006@default-local-paging-1d46;2", "") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:5] Dial("Local/6002@default-local-paging-84a9;2", "SIP/6002,5") in new stack [2012-01-09 09:32:32] -- Executing [paging@all-local-extension:5] Dial("Local/6001@default-local-paging-85da;2", "SIP/6001,5") in new stack [2012-01-09 09:32:32] == Using UDPTL TOS bits 184 [2012-01-09 09:32:32] == Using UDPTL CoS mark 5 [2012-01-09 09:32:32] -- Executing [6006@default-local-paging:2] Set("Local/6006@default-local-paging-1d46;2", "LOCAL_EXTEN=6006") in new stack [2012-01-09 09:32:32] WARNING[11125]: app_dial.c:2218 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Unknown) [2012-01-09 09:32:32] == Everyone is busy/congested at this time (1:0/0/1) [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:6] Hangup("Local/1004@default-local-paging-63b5;2", "20") in new stack [2012-01-09 09:32:33] == Spawn extension (all-local-extension, paging, 6) exited non-zero on 'Local/1004@default-local-paging-63b5;2' [2012-01-09 09:32:33] -- Executing [h@all-local-extension:1] Goto("Local/1004@default-local-paging-63b5;2", "all-hangup,s,1") in new stack [2012-01-09 09:32:33] -- Executing [6005@default-local-paging:3] SIPAddHeader("Local/6005@default-local-paging-85a8;2", "Alert-Info: Auto Answer") in new stack [2012-01-09 09:32:33] -- Executing [6004@default-local-paging:4] Set("Local/6004@default-local-paging-cb33;2", "LOCAL_CHANNEL=SIP/6004") in new stack [2012-01-09 09:32:33] -- Executing [6003@default-local-paging:4] Set("Local/6003@default-local-paging-364a;2", "LOCAL_CHANNEL=SIP/6003") in new stack [2012-01-09 09:32:33] -- Executing [6004@default-local-paging:5] Gosub("Local/6004@default-local-paging-cb33;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:1] Set("Local/6004@default-local-paging-cb33;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:2] Set("Local/6004@default-local-paging-cb33;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:3] GotoIf("Local/6004@default-local-paging-cb33;2", "0?6") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:4] Set("Local/6004@default-local-paging-cb33;2", "OUTBOUND_GROUP=6004@INCOMING") in new stack [2012-01-09 09:32:33] -- Executing [6005@default-local-paging:4] Set("Local/6005@default-local-paging-85a8;2", "LOCAL_CHANNEL=SIP/6005") in new stack [2012-01-09 09:32:33] -- Goto (all-hangup,s,1) [2012-01-09 09:32:33] -- Executing [s@all-hangup:1] Set("Local/1004@default-local-paging-63b5;2", "CDR(hangupside)=") in new stack [2012-01-09 09:32:33] -- Executing [6003@default-local-paging:5] Gosub("Local/6003@default-local-paging-364a;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:33] -- Executing [6006@default-local-paging:3] SIPAddHeader("Local/6006@default-local-paging-1d46;2", "Call-Info:\;answer-after=0") in new stack [2012-01-09 09:32:33] -- Executing [s@all-hangup:2] Set("Local/1004@default-local-paging-63b5;2", "CDR(hangupcause)=20") in new stack [2012-01-09 09:32:33] -- Executing [6005@default-local-paging:5] Gosub("Local/6005@default-local-paging-85a8;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:1] Set("Local/6005@default-local-paging-85a8;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:2] Set("Local/6005@default-local-paging-85a8;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:3] GotoIf("Local/6005@default-local-paging-85a8;2", "0?6") in new stack [2012-01-09 09:32:33] -- Executing [6006@default-local-paging:4] Set("Local/6006@default-local-paging-1d46;2", "LOCAL_CHANNEL=SIP/6006") in new stack [2012-01-09 09:32:33] -- Executing [6006@default-local-paging:5] Gosub("Local/6006@default-local-paging-1d46;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:4] Set("Local/6005@default-local-paging-85a8;2", "OUTBOUND_GROUP=6005@INCOMING") in new stack [2012-01-09 09:32:33] -- Executing [s@all-hangup:3] Set("Local/1004@default-local-paging-63b5;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:5] Dial("Local/6005@default-local-paging-85a8;2", "SIP/6005,5") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:5] Dial("Local/6004@default-local-paging-cb33;2", "SIP/6004,5") in new stack [2012-01-09 09:32:33] -- Executing [s@all-hangup:4] Set("Local/1004@default-local-paging-63b5;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:1] Set("Local/6006@default-local-paging-1d46;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:33] == Using UDPTL TOS bits 184 [2012-01-09 09:32:33] == Using UDPTL CoS mark 5 [2012-01-09 09:32:33] -- Executing [s@all-hangup:5] Set("Local/1004@default-local-paging-63b5;2", "CDR(peerip)=") in new stack [2012-01-09 09:32:33] == Using UDPTL TOS bits 184 [2012-01-09 09:32:33] == Using SIP RTP TOS bits 184 [2012-01-09 09:32:33] == Using UDPTL TOS bits 184 [2012-01-09 09:32:33] == Using SIP RTP CoS mark 5 [2012-01-09 09:32:33] == Using UDPTL CoS mark 5 [2012-01-09 09:32:33] == Using SIP RTP TOS bits 184 [2012-01-09 09:32:33] -- Called SIP/6000 [2012-01-09 09:32:33] == Using SIP RTP CoS mark 5 [2012-01-09 09:32:33] == Using UDPTL CoS mark 5 [2012-01-09 09:32:33] -- Executing [6007@default-local-paging:1] NoCDR("Local/6007@default-local-paging-6ca2;2", "") in new stack [2012-01-09 09:32:33] -- Called 6007@default-local-paging [2012-01-09 09:32:33] -- Executing [paging@all-local-extension:1] Set("Local/6003@default-local-paging-364a;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:34] -- Executing [6007@default-local-paging:2] Set("Local/6007@default-local-paging-6ca2;2", "LOCAL_EXTEN=6007") in new stack [2012-01-09 09:32:34] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:34] -- Executing [s@all-hangup:6] Set("Local/1004@default-local-paging-63b5;2", "CDR(recvip)=") in new stack [2012-01-09 09:32:34] == Using SIP RTP TOS bits 184 [2012-01-09 09:32:34] == Using SIP RTP CoS mark 5 [2012-01-09 09:32:34] -- Executing [paging@all-local-extension:2] Set("Local/6006@default-local-paging-1d46;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:34] -- Executing [6010@default-local-paging:1] NoCDR("Local/6010@default-local-paging-325e;2", "") in new stack [2012-01-09 09:32:34] -- Called 6010@default-local-paging [2012-01-09 09:32:34] -- Executing [s@all-hangup:7] Set("Local/1004@default-local-paging-63b5;2", "CDR(fromchan)=") in new stack [2012-01-09 09:32:34] -- Executing [paging@all-local-extension:2] Set("Local/6003@default-local-paging-364a;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:34] -- Executing [paging@all-local-extension:3] GotoIf("Local/6003@default-local-paging-364a;2", "0?6") in new stack [2012-01-09 09:32:34] -- Executing [paging@all-local-extension:4] Set("Local/6003@default-local-paging-364a;2", "OUTBOUND_GROUP=6003@INCOMING") in new stack [2012-01-09 09:32:34] -- Executing [paging@all-local-extension:5] Dial("Local/6003@default-local-paging-364a;2", "SIP/6003,5") in new stack [2012-01-09 09:32:34] -- SIP/6000-00000001 is ringing [2012-01-09 09:32:34] -- Executing [paging@all-local-extension:3] GotoIf("Local/6006@default-local-paging-1d46;2", "0?6") in new stack [2012-01-09 09:32:34] == Using UDPTL TOS bits 184 [2012-01-09 09:32:34] == Using UDPTL CoS mark 5 [2012-01-09 09:32:34] WARNING[11144]: app_dial.c:2218 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Unknown) [2012-01-09 09:32:34] == Everyone is busy/congested at this time (1:0/0/1) [2012-01-09 09:32:34] -- Executing [paging@all-local-extension:4] Set("Local/6006@default-local-paging-1d46;2", "OUTBOUND_GROUP=6006@INCOMING") in new stack [2012-01-09 09:32:34] -- Executing [paging@all-local-extension:6] Hangup("Local/6004@default-local-paging-cb33;2", "20") in new stack [2012-01-09 09:32:34] -- Executing [6007@default-local-paging:3] SIPAddHeader("Local/6007@default-local-paging-6ca2;2", "Alert-Info: Auto Answer") in new stack [2012-01-09 09:32:34] -- Local/6000@default-local-paging-658c;1 is ringing [2012-01-09 09:32:34] == Spawn extension (all-local-extension, paging, 6) exited non-zero on 'Local/6004@default-local-paging-cb33;2' [2012-01-09 09:32:34] -- Executing [s@all-hangup:8] Set("Local/1004@default-local-paging-63b5;2", "CDR(uri)=") in new stack [2012-01-09 09:32:34] -- Executing [h@all-local-extension:1] Goto("Local/6004@default-local-paging-cb33;2", "all-hangup,s,1") in new stack [2012-01-09 09:32:34] -- Executing [s@all-hangup:9] Set("Local/1004@default-local-paging-63b5;2", "CDR(useragent)=") in new stack [2012-01-09 09:32:34] -- Executing [6010@default-local-paging:2] Set("Local/6010@default-local-paging-325e;2", "LOCAL_EXTEN=6010") in new stack [2012-01-09 09:32:34] -- Executing [s@all-hangup:10] Set("Local/1004@default-local-paging-63b5;2", "CDR(llp)=") in new stack [2012-01-09 09:32:34] -- Goto (all-hangup,s,1) [2012-01-09 09:32:34] -- Executing [s@all-hangup:1] Set("Local/6004@default-local-paging-cb33;2", "CDR(hangupside)=") in new stack [2012-01-09 09:32:34] -- SIP/6000-00000001 answered Local/6000@default-local-paging-658c;2 [2012-01-09 09:32:34] -- Executing [s@all-hangup:11] Set("Local/1004@default-local-paging-63b5;2", "CDR(rlp)=") in new stack [2012-01-09 09:32:34] -- Executing [paging@all-local-extension:5] Dial("Local/6006@default-local-paging-1d46;2", "SIP/6006,5") in new stack [2012-01-09 09:32:34] -- Executing [6007@default-local-paging:4] Set("Local/6007@default-local-paging-6ca2;2", "LOCAL_CHANNEL=SIP/6007") in new stack [2012-01-09 09:32:35] -- Called SIP/6001 [2012-01-09 09:32:35] -- Executing [6007@default-local-paging:5] Gosub("Local/6007@default-local-paging-6ca2;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:35] -- Executing [paging@all-local-extension:1] Set("Local/6007@default-local-paging-6ca2;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:35] -- Executing [paging@all-local-extension:2] Set("Local/6007@default-local-paging-6ca2;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:35] -- Executing [s@all-hangup:12] Set("Local/1004@default-local-paging-63b5;2", "CDR(ljitt)=") in new stack [2012-01-09 09:32:35] -- Executing [s@all-hangup:13] Set("Local/1004@default-local-paging-63b5;2", "CDR(rjitt)=") in new stack [2012-01-09 09:32:35] -- Executing [s@all-hangup:14] GotoIf("Local/1004@default-local-paging-63b5;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:32:35] == Using UDPTL TOS bits 184 [2012-01-09 09:32:35] == Using UDPTL CoS mark 5 [2012-01-09 09:32:35] -- Executing [6010@default-local-paging:3] SIPAddHeader("Local/6010@default-local-paging-325e;2", "Alert-Info: Auto Answer") in new stack [2012-01-09 09:32:35] -- Executing [s@all-hangup:2] Set("Local/6004@default-local-paging-cb33;2", "CDR(hangupcause)=20") in new stack [2012-01-09 09:32:35] == Using UDPTL TOS bits 184 [2012-01-09 09:32:35] -- Executing [paging@all-local-extension:3] GotoIf("Local/6007@default-local-paging-6ca2;2", "0?6") in new stack [2012-01-09 09:32:35] == Using UDPTL CoS mark 5 [2012-01-09 09:32:35] == Using SIP RTP TOS bits 184 [2012-01-09 09:32:35] == Using SIP RTP CoS mark 5 [2012-01-09 09:32:35] -- Executing [paging@all-local-extension:4] Set("Local/6007@default-local-paging-6ca2;2", "OUTBOUND_GROUP=6007@INCOMING") in new stack [2012-01-09 09:32:35] -- Executing [s@all-hangup:3] Set("Local/6004@default-local-paging-cb33;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:32:35] == Using SIP RTP TOS bits 184 [2012-01-09 09:32:35] == Using SIP RTP CoS mark 5 [2012-01-09 09:32:35] -- Called 6057@default-local-paging [2012-01-09 09:32:35] -- Called 6989@default-local-paging [2012-01-09 09:32:35] -- Executing [6989@default-local-paging:1] NoCDR("Local/6989@default-local-paging-62a4;2", "") in new stack [2012-01-09 09:32:35] -- Executing [6989@default-local-paging:2] Set("Local/6989@default-local-paging-62a4;2", "LOCAL_EXTEN=6989") in new stack [2012-01-09 09:32:35] -- Executing [6989@default-local-paging:3] SIPAddHeader("Local/6989@default-local-paging-62a4;2", "Alert-Info: Auto Answer") in new stack [2012-01-09 09:32:35] -- Executing [6010@default-local-paging:4] Set("Local/6010@default-local-paging-325e;2", "LOCAL_CHANNEL=SIP/6010") in new stack [2012-01-09 09:32:35] -- Playing 'beep.g722' (language 'en') [2012-01-09 09:32:35] -- Executing [s@all-hangup:4] Set("Local/6004@default-local-paging-cb33;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:32:35] -- Executing [6057@default-local-paging:1] NoCDR("Local/6057@default-local-paging-8231;2", "") in new stack [2012-01-09 09:32:35] -- Executing [s@all-hangup:5] Set("Local/6004@default-local-paging-cb33;2", "CDR(peerip)=") in new stack [2012-01-09 09:32:35] -- Called SIP/6002 [2012-01-09 09:32:35] -- Called SIP/6005 [2012-01-09 09:32:36] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:36] -- Executing [6989@default-local-paging:4] Set("Local/6989@default-local-paging-62a4;2", "LOCAL_CHANNEL=SIP/6989") in new stack [2012-01-09 09:32:36] -- SIP/6001-00000003 answered Local/6001@default-local-paging-85da;2 [2012-01-09 09:32:36] -- Executing [6057@default-local-paging:2] Set("Local/6057@default-local-paging-8231;2", "LOCAL_EXTEN=6057") in new stack [2012-01-09 09:32:36] -- Executing [s@all-hangup:6] Set("Local/6004@default-local-paging-cb33;2", "CDR(recvip)=") in new stack [2012-01-09 09:32:36] -- Executing [paging@all-local-extension:5] Dial("Local/6007@default-local-paging-6ca2;2", "SIP/6007,5") in new stack [2012-01-09 09:32:36] == Using UDPTL TOS bits 184 [2012-01-09 09:32:36] -- Goto (all-hangup,s,15) [2012-01-09 09:32:36] -- Executing [s@all-hangup:15] ResetCDR("Local/1004@default-local-paging-63b5;2", "w") in new stack [2012-01-09 09:32:36] == Using UDPTL CoS mark 5 [2012-01-09 09:32:36] WARNING[11161]: app_dial.c:2218 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Unknown) [2012-01-09 09:32:36] == Everyone is busy/congested at this time (1:0/0/1) [2012-01-09 09:32:36] -- Executing [6989@default-local-paging:5] Gosub("Local/6989@default-local-paging-62a4;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:36] -- Executing [paging@all-local-extension:1] Set("Local/6989@default-local-paging-62a4;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:36] -- Executing [s@all-hangup:7] Set("Local/6004@default-local-paging-cb33;2", "CDR(fromchan)=") in new stack [2012-01-09 09:32:36] -- Executing [6010@default-local-paging:5] Gosub("Local/6010@default-local-paging-325e;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:36] -- Executing [s@all-hangup:16] NoCDR("Local/1004@default-local-paging-63b5;2", "") in new stack [2012-01-09 09:32:36] -- Local/6000@default-local-paging-658c;1 answered [2012-01-09 09:32:36] -- Executing [paging@all-local-extension:6] Hangup("Local/6007@default-local-paging-6ca2;2", "20") in new stack [2012-01-09 09:32:36] -- Executing [6057@default-local-paging:3] SIPAddHeader("Local/6057@default-local-paging-8231;2", "Alert-Info: Ring Answer") in new stack [2012-01-09 09:32:36] -- Executing [paging@all-local-extension:2] Set("Local/6989@default-local-paging-62a4;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:36] -- Executing [s@all-hangup:8] Set("Local/6004@default-local-paging-cb33;2", "CDR(uri)=") in new stack [2012-01-09 09:32:36] -- Executing [s@all-hangup:17] Hangup("Local/1004@default-local-paging-63b5;2", "") in new stack [2012-01-09 09:32:36] -- Executing [paging@all-local-extension:3] GotoIf("Local/6989@default-local-paging-62a4;2", "0?6") in new stack [2012-01-09 09:32:36] -- Executing [paging@all-local-extension:4] Set("Local/6989@default-local-paging-62a4;2", "OUTBOUND_GROUP=6989@INCOMING") in new stack [2012-01-09 09:32:36] -- Called SIP/6006 [2012-01-09 09:32:36] == Spawn extension (all-local-extension, paging, 6) exited non-zero on 'Local/6007@default-local-paging-6ca2;2' [2012-01-09 09:32:36] -- Executing [h@all-local-extension:1] Goto("Local/6007@default-local-paging-6ca2;2", "all-hangup,s,1") in new stack [2012-01-09 09:32:37] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/1004@default-local-paging-63b5;2' [2012-01-09 09:32:37] == Extension Changed 6000[default-local] new state InUse for Notify User 6001 [2012-01-09 09:32:37] -- Executing [paging@all-local-extension:1] Set("Local/6010@default-local-paging-325e;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:37] -- SIP/6005-00000002 answered Local/6005@default-local-paging-85a8;2 [2012-01-09 09:32:37] -- Executing [6057@default-local-paging:4] Set("Local/6057@default-local-paging-8231;2", "LOCAL_CHANNEL=SIP/6057") in new stack [2012-01-09 09:32:37] -- Executing [s@all-hangup:9] Set("Local/6004@default-local-paging-cb33;2", "CDR(useragent)=") in new stack [2012-01-09 09:32:37] -- Created MeetMe conference 1023 for conference '2093756203d' [2012-01-09 09:32:37] -- Goto (all-hangup,s,1) [2012-01-09 09:32:37] -- Executing [s@all-hangup:1] Set("Local/6007@default-local-paging-6ca2;2", "CDR(hangupside)=") in new stack [2012-01-09 09:32:37] -- Executing [s@all-hangup:10] Set("Local/6004@default-local-paging-cb33;2", "CDR(llp)=") in new stack [2012-01-09 09:32:37] -- Executing [paging@all-local-extension:2] Set("Local/6010@default-local-paging-325e;2", "GROUPCOUNT=0") in new stack [2012-01-09 09:32:37] -- SIP/6002-00000004 answered Local/6002@default-local-paging-84a9;2 [2012-01-09 09:32:37] -- Executing [s@all-hangup:2] Set("Local/6007@default-local-paging-6ca2;2", "CDR(hangupcause)=20") in new stack [2012-01-09 09:32:37] -- Executing [6057@default-local-paging:5] Gosub("Local/6057@default-local-paging-8231;2", "all-local-extension,paging,1") in new stack [2012-01-09 09:32:37] -- Executing [s@all-hangup:11] Set("Local/6004@default-local-paging-cb33;2", "CDR(rlp)=") in new stack [2012-01-09 09:32:37] -- Executing [paging@all-local-extension:5] Dial("Local/6989@default-local-paging-62a4;2", "SIP/6989,5") in new stack [2012-01-09 09:32:37] -- Executing [paging@all-local-extension:3] GotoIf("Local/6010@default-local-paging-325e;2", "0?6") in new stack [2012-01-09 09:32:37] -- Executing [s@all-hangup:3] Set("Local/6007@default-local-paging-6ca2;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:32:37] -- Executing [s@all-hangup:12] Set("Local/6004@default-local-paging-cb33;2", "CDR(ljitt)=") in new stack [2012-01-09 09:32:37] -- Executing [paging@all-local-extension:1] Set("Local/6057@default-local-paging-8231;2", "GROUPCOUNT=1") in new stack [2012-01-09 09:32:37] -- Executing [s@all-hangup:4] Set("Local/6007@default-local-paging-6ca2;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:32:37] -- Executing [paging@all-local-extension:4] Set("Local/6010@default-local-paging-325e;2", "OUTBOUND_GROUP=6010@INCOMING") in new stack [2012-01-09 09:32:37] -- Local/6001@default-local-paging-85da;1 answered [2012-01-09 09:32:37] -- Executing [s@all-hangup:13] Set("Local/6004@default-local-paging-cb33;2", "CDR(rjitt)=") in new stack [2012-01-09 09:32:38] -- Executing [paging@all-local-extension:2] Set("Local/6057@default-local-paging-8231;2", "GROUPCOUNT=1") in new stack [2012-01-09 09:32:38] -- Executing [s@all-hangup:5] Set("Local/6007@default-local-paging-6ca2;2", "CDR(peerip)=") in new stack [2012-01-09 09:32:38] -- Executing [s@all-hangup:14] GotoIf("Local/6004@default-local-paging-cb33;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:32:38] -- Executing [paging@all-local-extension:5] Dial("Local/6010@default-local-paging-325e;2", "SIP/6010,5") in new stack [2012-01-09 09:32:38] -- Executing [s@all-hangup:6] Set("Local/6007@default-local-paging-6ca2;2", "CDR(recvip)=") in new stack [2012-01-09 09:32:38] -- Local/6005@default-local-paging-85a8;1 answered [2012-01-09 09:32:38] -- Called SIP/6003 [2012-01-09 09:32:38] -- Executing [s@all-hangup:7] Set("Local/6007@default-local-paging-6ca2;2", "CDR(fromchan)=") in new stack [2012-01-09 09:32:38] -- Goto (all-hangup,s,15) [2012-01-09 09:32:38] -- Executing [s@all-hangup:15] ResetCDR("Local/6004@default-local-paging-cb33;2", "w") in new stack [2012-01-09 09:32:38] -- Executing [paging@all-local-extension:3] GotoIf("Local/6057@default-local-paging-8231;2", "1?6") in new stack [2012-01-09 09:32:38] -- Executing [s@all-hangup:8] Set("Local/6007@default-local-paging-6ca2;2", "CDR(uri)=") in new stack [2012-01-09 09:32:38] -- Executing [s@all-hangup:16] NoCDR("Local/6004@default-local-paging-cb33;2", "") in new stack [2012-01-09 09:32:38] -- Executing [s@all-hangup:17] Hangup("Local/6004@default-local-paging-cb33;2", "") in new stack [2012-01-09 09:32:38] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6004@default-local-paging-cb33;2' [2012-01-09 09:32:39] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:39] == Using UDPTL TOS bits 184 [2012-01-09 09:32:39] == Using UDPTL CoS mark 5 [2012-01-09 09:32:39] WARNING[11202]: app_dial.c:2218 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Unknown) [2012-01-09 09:32:39] == Everyone is busy/congested at this time (1:0/0/1) [2012-01-09 09:32:39] -- Executing [s@all-hangup:9] Set("Local/6007@default-local-paging-6ca2;2", "CDR(useragent)=") in new stack [2012-01-09 09:32:39] -- Local/6002@default-local-paging-84a9;1 answered [2012-01-09 09:32:39] -- Executing [s@all-hangup:10] Set("Local/6007@default-local-paging-6ca2;2", "CDR(llp)=") in new stack [2012-01-09 09:32:39] -- Goto (all-local-extension,paging,6) [2012-01-09 09:32:39] -- Executing [paging@all-local-extension:6] Hangup("Local/6057@default-local-paging-8231;2", "0") in new stack [2012-01-09 09:32:39] -- Executing [s@all-hangup:11] Set("Local/6007@default-local-paging-6ca2;2", "CDR(rlp)=") in new stack [2012-01-09 09:32:39] -- SIP/6006-00000005 answered Local/6006@default-local-paging-1d46;2 [2012-01-09 09:32:39] -- Executing [paging@all-local-extension:6] Hangup("Local/6989@default-local-paging-62a4;2", "20") in new stack [2012-01-09 09:32:40] == Spawn extension (all-local-extension, paging, 6) exited non-zero on 'Local/6057@default-local-paging-8231;2' [2012-01-09 09:32:40] -- Executing [h@all-local-extension:1] Goto("Local/6057@default-local-paging-8231;2", "all-hangup,s,1") in new stack [2012-01-09 09:32:40] -- Executing [s@all-hangup:12] Set("Local/6007@default-local-paging-6ca2;2", "CDR(ljitt)=") in new stack [2012-01-09 09:32:40] == Using UDPTL TOS bits 184 [2012-01-09 09:32:40] == Using UDPTL CoS mark 5 [2012-01-09 09:32:40] -- Executing [s@all-hangup:13] Set("Local/6007@default-local-paging-6ca2;2", "CDR(rjitt)=") in new stack [2012-01-09 09:32:40] == Spawn extension (all-local-extension, paging, 6) exited non-zero on 'Local/6989@default-local-paging-62a4;2' [2012-01-09 09:32:40] -- Executing [h@all-local-extension:1] Goto("Local/6989@default-local-paging-62a4;2", "all-hangup,s,1") in new stack [2012-01-09 09:32:40] == Using SIP RTP TOS bits 184 [2012-01-09 09:32:40] -- Executing [s@all-hangup:14] GotoIf("Local/6007@default-local-paging-6ca2;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:32:41] -- Goto (all-hangup,s,1) [2012-01-09 09:32:41] -- Executing [s@all-hangup:1] Set("Local/6057@default-local-paging-8231;2", "CDR(hangupside)=") in new stack [2012-01-09 09:32:41] == Using SIP RTP CoS mark 5 [2012-01-09 09:32:41] -- SIP/6003-00000006 answered Local/6003@default-local-paging-364a;2 [2012-01-09 09:32:41] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:42] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:43] -- Goto (all-hangup,s,1) [2012-01-09 09:32:43] -- Executing [s@all-hangup:1] Set("Local/6989@default-local-paging-62a4;2", "CDR(hangupside)=") in new stack [2012-01-09 09:32:43] -- Executing [s@all-hangup:2] Set("Local/6989@default-local-paging-62a4;2", "CDR(hangupcause)=20") in new stack [2012-01-09 09:32:43] -- Executing [s@all-hangup:3] Set("Local/6989@default-local-paging-62a4;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:32:43] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:43] -- Goto (all-hangup,s,15) [2012-01-09 09:32:43] -- Executing [s@all-hangup:15] ResetCDR("Local/6007@default-local-paging-6ca2;2", "w") in new stack [2012-01-09 09:32:43] -- Executing [s@all-hangup:4] Set("Local/6989@default-local-paging-62a4;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:32:43] -- Executing [s@all-hangup:2] Set("Local/6057@default-local-paging-8231;2", "CDR(hangupcause)=0") in new stack [2012-01-09 09:32:43] -- Executing [s@all-hangup:3] Set("Local/6057@default-local-paging-8231;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:32:43] -- Executing [s@all-hangup:16] NoCDR("Local/6007@default-local-paging-6ca2;2", "") in new stack [2012-01-09 09:32:44] -- Executing [s@all-hangup:5] Set("Local/6989@default-local-paging-62a4;2", "CDR(peerip)=") in new stack [2012-01-09 09:32:44] -- Local/6006@default-local-paging-1d46;1 answered [2012-01-09 09:32:44] -- Executing [s@all-hangup:17] Hangup("Local/6007@default-local-paging-6ca2;2", "") in new stack [2012-01-09 09:32:44] -- Executing [s@all-hangup:4] Set("Local/6057@default-local-paging-8231;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:32:45] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:45] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:46] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:46] -- Executing [s@all-hangup:6] Set("Local/6989@default-local-paging-62a4;2", "CDR(recvip)=") in new stack [2012-01-09 09:32:46] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6007@default-local-paging-6ca2;2' [2012-01-09 09:32:46] -- Executing [s@all-hangup:5] Set("Local/6057@default-local-paging-8231;2", "CDR(peerip)=") in new stack [2012-01-09 09:32:46] -- Executing [s@all-hangup:7] Set("Local/6989@default-local-paging-62a4;2", "CDR(fromchan)=") in new stack [2012-01-09 09:32:46] -- Executing [s@all-hangup:6] Set("Local/6057@default-local-paging-8231;2", "CDR(recvip)=") in new stack [2012-01-09 09:32:47] -- Executing [s@all-hangup:7] Set("Local/6057@default-local-paging-8231;2", "CDR(fromchan)=") in new stack [2012-01-09 09:32:47] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:47] -- Executing [s@all-hangup:8] Set("Local/6057@default-local-paging-8231;2", "CDR(uri)=") in new stack [2012-01-09 09:32:47] -- Executing [s@all-hangup:8] Set("Local/6989@default-local-paging-62a4;2", "CDR(uri)=") in new stack [2012-01-09 09:32:47] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:48] -- Executing [s@all-hangup:9] Set("Local/6989@default-local-paging-62a4;2", "CDR(useragent)=") in new stack [2012-01-09 09:32:48] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:48] -- Executing [s@all-hangup:9] Set("Local/6057@default-local-paging-8231;2", "CDR(useragent)=") in new stack [2012-01-09 09:32:48] -- Executing [s@all-hangup:10] Set("Local/6057@default-local-paging-8231;2", "CDR(llp)=") in new stack [2012-01-09 09:32:48] -- Executing [s@all-hangup:11] Set("Local/6057@default-local-paging-8231;2", "CDR(rlp)=") in new stack [2012-01-09 09:32:48] -- Executing [s@all-hangup:10] Set("Local/6989@default-local-paging-62a4;2", "CDR(llp)=") in new stack [2012-01-09 09:32:48] -- Local/6003@default-local-paging-364a;1 answered [2012-01-09 09:32:48] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:49] -- Executing [s@all-hangup:12] Set("Local/6057@default-local-paging-8231;2", "CDR(ljitt)=") in new stack [2012-01-09 09:32:49] WARNING[11128]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;2 [2012-01-09 09:32:49] -- Executing [s@all-hangup:11] Set("Local/6989@default-local-paging-62a4;2", "CDR(rlp)=") in new stack [2012-01-09 09:32:49] -- Executing [s@all-hangup:13] Set("Local/6057@default-local-paging-8231;2", "CDR(rjitt)=") in new stack [2012-01-09 09:32:50] -- Executing [s@all-hangup:12] Set("Local/6989@default-local-paging-62a4;2", "CDR(ljitt)=") in new stack [2012-01-09 09:32:50] -- Executing [s@all-hangup:14] GotoIf("Local/6057@default-local-paging-8231;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:32:51] -- Goto (all-hangup,s,15) [2012-01-09 09:32:51] -- Executing [s@all-hangup:15] ResetCDR("Local/6057@default-local-paging-8231;2", "w") in new stack [2012-01-09 09:32:51] -- Executing [s@all-hangup:13] Set("Local/6989@default-local-paging-62a4;2", "CDR(rjitt)=") in new stack [2012-01-09 09:32:51] -- Executing [s@all-hangup:16] NoCDR("Local/6057@default-local-paging-8231;2", "") in new stack [2012-01-09 09:32:52] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:52] -- Executing [s@all-hangup:14] GotoIf("Local/6989@default-local-paging-62a4;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:32:52] WARNING[11149]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;2 [2012-01-09 09:32:53] -- Executing [s@all-hangup:17] Hangup("Local/6057@default-local-paging-8231;2", "") in new stack [2012-01-09 09:32:53] -- Goto (all-hangup,s,15) [2012-01-09 09:32:53] -- Executing [s@all-hangup:15] ResetCDR("Local/6989@default-local-paging-62a4;2", "w") in new stack [2012-01-09 09:32:53] WARNING[11140]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;2 [2012-01-09 09:32:53] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6057@default-local-paging-8231;2' [2012-01-09 09:32:53] -- Executing [s@all-hangup:16] NoCDR("Local/6989@default-local-paging-62a4;2", "") in new stack [2012-01-09 09:32:53] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:54] WARNING[11130]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;2 [2012-01-09 09:32:54] -- Called SIP/6010 [2012-01-09 09:32:54] -- Executing [s@all-hangup:17] Hangup("Local/6989@default-local-paging-62a4;2", "") in new stack [2012-01-09 09:32:54] WARNING[11139]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;1 [2012-01-09 09:32:55] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6989@default-local-paging-62a4;2' [2012-01-09 09:32:55] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:56] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '6001' is now UNREACHABLE! Last qualify: 39 [2012-01-09 09:32:56] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:32:57] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '6005' is now UNREACHABLE! Last qualify: 13 [2012-01-09 09:32:57] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '6010' is now UNREACHABLE! Last qualify: 16 [2012-01-09 09:32:58] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '6003' is now UNREACHABLE! Last qualify: 12 [2012-01-09 09:32:58] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:00] -- Nobody picked up in 5000 ms [2012-01-09 09:33:00] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '6000' is now UNREACHABLE! Last qualify: 37 [2012-01-09 09:33:01] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:01] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:01] WARNING[11128]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;2 [2012-01-09 09:33:02] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '6006' is now UNREACHABLE! Last qualify: 12 [2012-01-09 09:33:02] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:02] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '0762*100' is now UNREACHABLE! Last qualify: 27 [2012-01-09 09:33:02] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:03] WARNING[11142]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6003@default-local-paging-364a;2 [2012-01-09 09:33:03] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer 'siptrunk' is now UNREACHABLE! Last qualify: 36 [2012-01-09 09:33:03] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:03] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:03] -- Executing [paging@all-local-extension:6] Hangup("Local/6010@default-local-paging-325e;2", "0") in new stack [2012-01-09 09:33:03] WARNING[11157]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6006@default-local-paging-1d46;2 [2012-01-09 09:33:04] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:04] WARNING[11130]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;2 [2012-01-09 09:33:04] == Spawn extension (all-local-extension, paging, 6) exited non-zero on 'Local/6010@default-local-paging-325e;2' [2012-01-09 09:33:04] -- Executing [h@all-local-extension:1] Goto("Local/6010@default-local-paging-325e;2", "all-hangup,s,1") in new stack [2012-01-09 09:33:04] WARNING[9697]: chan_sip.c:3626 retrans_pkt: Retransmission timeout reached on transmission 03a754496f40c1221a8d055c6791ec48@192.168.30.254:5060 for seqno 102 (Critical Request) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions Packet timed out after 10891ms with no response [2012-01-09 09:33:04] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:05] -- Goto (all-hangup,s,1) [2012-01-09 09:33:05] -- Executing [s@all-hangup:1] Set("Local/6010@default-local-paging-325e;2", "CDR(hangupside)=") in new stack [2012-01-09 09:33:05] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:05] -- Executing [s@all-hangup:2] Set("Local/6010@default-local-paging-325e;2", "CDR(hangupcause)=0") in new stack [2012-01-09 09:33:05] WARNING[11149]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;2 [2012-01-09 09:33:05] -- Executing [s@all-hangup:3] Set("Local/6010@default-local-paging-325e;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:33:05] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:06] -- Executing [s@all-hangup:4] Set("Local/6010@default-local-paging-325e;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:33:06] -- Executing [s@all-hangup:5] Set("Local/6010@default-local-paging-325e;2", "CDR(peerip)=") in new stack [2012-01-09 09:33:06] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:07] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:07] -- Executing [s@all-hangup:6] Set("Local/6010@default-local-paging-325e;2", "CDR(recvip)=") in new stack [2012-01-09 09:33:07] -- Executing [s@all-hangup:7] Set("Local/6010@default-local-paging-325e;2", "CDR(fromchan)=") in new stack [2012-01-09 09:33:07] -- Executing [s@all-hangup:8] Set("Local/6010@default-local-paging-325e;2", "CDR(uri)=") in new stack [2012-01-09 09:33:07] -- Executing [s@all-hangup:9] Set("Local/6010@default-local-paging-325e;2", "CDR(useragent)=") in new stack [2012-01-09 09:33:07] -- Executing [s@all-hangup:10] Set("Local/6010@default-local-paging-325e;2", "CDR(llp)=") in new stack [2012-01-09 09:33:07] -- Executing [s@all-hangup:11] Set("Local/6010@default-local-paging-325e;2", "CDR(rlp)=") in new stack [2012-01-09 09:33:07] -- Executing [s@all-hangup:12] Set("Local/6010@default-local-paging-325e;2", "CDR(ljitt)=") in new stack [2012-01-09 09:33:07] -- Executing [s@all-hangup:13] Set("Local/6010@default-local-paging-325e;2", "CDR(rjitt)=") in new stack [2012-01-09 09:33:07] -- Executing [s@all-hangup:14] GotoIf("Local/6010@default-local-paging-325e;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:33:07] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:08] -- Goto (all-hangup,s,15) [2012-01-09 09:33:08] -- Executing [s@all-hangup:15] ResetCDR("Local/6010@default-local-paging-325e;2", "w") in new stack [2012-01-09 09:33:08] WARNING[11127]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;1 [2012-01-09 09:33:08] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:08] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:08] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:09] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:09] -- Executing [s@all-hangup:16] NoCDR("Local/6010@default-local-paging-325e;2", "") in new stack [2012-01-09 09:33:09] WARNING[11139]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;1 [2012-01-09 09:33:09] -- Executing [s@all-hangup:17] Hangup("Local/6010@default-local-paging-325e;2", "") in new stack [2012-01-09 09:33:09] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6010@default-local-paging-325e;2' [2012-01-09 09:33:11] WARNING[11140]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;2 [2012-01-09 09:33:11] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:12] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:12] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '4001' is now UNREACHABLE! Last qualify: 53 [2012-01-09 09:33:13] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:13] WARNING[11129]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;1 [2012-01-09 09:33:13] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:13] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:14] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '2003' is now UNREACHABLE! Last qualify: 12 [2012-01-09 09:33:15] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:15] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:17] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:17] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '4000' is now UNREACHABLE! Last qualify: 21 [2012-01-09 09:33:17] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:17] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:18] WARNING[11148]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;1 [2012-01-09 09:33:18] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '5009' is now UNREACHABLE! Last qualify: 23 [2012-01-09 09:33:18] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:18] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '5010' is now UNREACHABLE! Last qualify: 19 [2012-01-09 09:33:18] WARNING[11142]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6003@default-local-paging-364a;2 [2012-01-09 09:33:19] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:19] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '6002' is now UNREACHABLE! Last qualify: 9 [2012-01-09 09:33:20] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:20] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:21] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:22] WARNING[11129]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;1 [2012-01-09 09:33:22] WARNING[11129]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;1 [2012-01-09 09:33:22] WARNING[11129]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;1 [2012-01-09 09:33:22] WARNING[11129]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;1 [2012-01-09 09:33:23] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:23] WARNING[11129]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;1 [2012-01-09 09:33:23] WARNING[11156]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6006@default-local-paging-1d46;1 [2012-01-09 09:33:24] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:24] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer 'allworx24x' is now UNREACHABLE! Last qualify: 4 [2012-01-09 09:33:24] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:24] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:25] WARNING[11127]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;1 [2012-01-09 09:33:25] WARNING[11139]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;1 [2012-01-09 09:33:25] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:28] WARNING[11157]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6006@default-local-paging-1d46;2 [2012-01-09 09:33:28] WARNING[11149]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;2 [2012-01-09 09:33:29] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:29] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:30] == Extension Changed 6010[default-local] new state Unavailable for Notify User 6001 [2012-01-09 09:33:30] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:31] WARNING[11128]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;2 [2012-01-09 09:33:31] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:32] WARNING[11141]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6003@default-local-paging-364a;1 [2012-01-09 09:33:33] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer '6057' is now UNREACHABLE! Last qualify: 4 [2012-01-09 09:33:33] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:33] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:33] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:34] WARNING[11142]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6003@default-local-paging-364a;2 [2012-01-09 09:33:34] WARNING[11140]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;2 [2012-01-09 09:33:34] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:35] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:35] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:35] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:36] WARNING[11148]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;1 [2012-01-09 09:33:36] WARNING[11129]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;1 [2012-01-09 09:33:37] == Extension Changed 5010[default-local] new state Unavailable for Notify User 6002 [2012-01-09 09:33:37] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:38] WARNING[11149]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;2 [2012-01-09 09:33:38] WARNING[11156]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6006@default-local-paging-1d46;1 [2012-01-09 09:33:40] WARNING[11130]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;2 [2012-01-09 09:33:40] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:40] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:40] WARNING[11157]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6006@default-local-paging-1d46;2 [2012-01-09 09:33:41] WARNING[11127]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;1 [2012-01-09 09:33:42] WARNING[11139]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;1 [2012-01-09 09:33:43] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:43] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:44] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:44] NOTICE[9697]: chan_sip.c:26017 sip_poke_noanswer: Peer 'switchvox' is now UNREACHABLE! Last qualify: 143 [2012-01-09 09:33:44] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:47] WARNING[11128]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;2 [2012-01-09 09:33:47] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:47] WARNING[11139]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;1 [2012-01-09 09:33:47] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:47] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:49] WARNING[11141]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6003@default-local-paging-364a;1 [2012-01-09 09:33:50] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:51] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:51] WARNING[11148]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;1 [2012-01-09 09:33:51] WARNING[11129]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;1 [2012-01-09 09:33:51] WARNING[11127]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;1 [2012-01-09 09:33:54] WARNING[11142]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6003@default-local-paging-364a;2 [2012-01-09 09:33:55] WARNING[11139]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;1 [2012-01-09 09:33:56] WARNING[11130]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;2 [2012-01-09 09:33:56] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:33:58] WARNING[11156]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6006@default-local-paging-1d46;1 [2012-01-09 09:33:59] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:01] WARNING[11157]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6006@default-local-paging-1d46;2 [2012-01-09 09:34:01] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:01] WARNING[11149]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;2 [2012-01-09 09:34:02] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:02] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:05] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:05] WARNING[11141]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6003@default-local-paging-364a;1 [2012-01-09 09:34:06] WARNING[11156]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6006@default-local-paging-1d46;1 [2012-01-09 09:34:07] WARNING[11129]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;1 [2012-01-09 09:34:07] WARNING[11148]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;1 [2012-01-09 09:34:07] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:08] WARNING[11142]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6003@default-local-paging-364a;2 [2012-01-09 09:34:08] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:09] WARNING[11139]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;1 [2012-01-09 09:34:09] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:09] WARNING[11127]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;1 [2012-01-09 09:34:10] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:10] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:11] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:12] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:13] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:13] WARNING[11128]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;2 [2012-01-09 09:34:14] WARNING[11140]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;2 [2012-01-09 09:34:14] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:15] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:15] WARNING[11129]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;1 [2012-01-09 09:34:15] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:15] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:17] WARNING[11156]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6006@default-local-paging-1d46;1 [2012-01-09 09:34:17] WARNING[11149]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;2 [2012-01-09 09:34:18] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:18] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:19] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:19] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:20] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:20] WARNING[11142]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6003@default-local-paging-364a;2 [2012-01-09 09:34:20] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:21] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:22] WARNING[11157]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6006@default-local-paging-1d46;2 [2012-01-09 09:34:22] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:23] WARNING[11128]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;2 [2012-01-09 09:34:23] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:23] WARNING[11130]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6001@default-local-paging-85da;2 [2012-01-09 09:34:24] WARNING[11139]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;1 [2012-01-09 09:34:25] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:25] WARNING[11127]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;1 [2012-01-09 09:34:26] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:27] WARNING[11141]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6003@default-local-paging-364a;1 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:27] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11148]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;1 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11140]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;2 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:28] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:29] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:29] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:30] WARNING[11121]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/6057-00000000 [2012-01-09 09:34:31] WARNING[11139]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6002@default-local-paging-84a9;1 [2012-01-09 09:34:32] WARNING[11148]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6005@default-local-paging-85a8;1 [2012-01-09 09:34:32] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:32] WARNING[11127]: channel.c:1474 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/6000@default-local-paging-658c;1 [2012-01-09 09:34:32] -- Hungup 'DAHDI/pseudo-1965092046' [2012-01-09 09:34:32] -- Executing [h@all-local-extension:1] Goto("Local/6002@default-local-paging-84a9;2", "all-hangup,s,1") in new stack [2012-01-09 09:34:33] -- Executing [h@all-local-extension:1] Goto("Local/6006@default-local-paging-1d46;2", "all-hangup,s,1") in new stack [2012-01-09 09:34:33] -- Goto (all-hangup,s,1) [2012-01-09 09:34:33] -- Executing [s@all-hangup:1] Set("Local/6002@default-local-paging-84a9;2", "CDR(hangupside)=") in new stack [2012-01-09 09:34:33] -- Executing [h@all-local-extension:1] Goto("Local/6005@default-local-paging-85a8;2", "all-hangup,s,1") in new stack [2012-01-09 09:34:33] -- Goto (all-hangup,s,1) [2012-01-09 09:34:33] -- Executing [s@all-hangup:1] Set("Local/6006@default-local-paging-1d46;2", "CDR(hangupside)=") in new stack [2012-01-09 09:34:33] -- Goto (all-hangup,s,1) [2012-01-09 09:34:33] -- Executing [s@all-hangup:1] Set("Local/6005@default-local-paging-85a8;2", "CDR(hangupside)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:2] Set("Local/6002@default-local-paging-84a9;2", "CDR(hangupcause)=16") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:2] Set("Local/6006@default-local-paging-1d46;2", "CDR(hangupcause)=16") in new stack [2012-01-09 09:34:33] == Spawn extension (default-super, *830, 100) exited non-zero on 'SIP/6057-00000000' [2012-01-09 09:34:33] -- Executing [h@all-local-extension:1] Goto("Local/6001@default-local-paging-85da;2", "all-hangup,s,1") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:3] Set("Local/6002@default-local-paging-84a9;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:3] Set("Local/6006@default-local-paging-1d46;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:2] Set("Local/6005@default-local-paging-85a8;2", "CDR(hangupcause)=16") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:4] Set("Local/6002@default-local-paging-84a9;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:3] Set("Local/6005@default-local-paging-85a8;2", "CDR(codec1)=slin") in new stack [2012-01-09 09:34:33] -- Goto (all-hangup,s,1) [2012-01-09 09:34:33] -- Executing [s@all-hangup:1] Set("Local/6001@default-local-paging-85da;2", "CDR(hangupside)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:4] Set("Local/6006@default-local-paging-1d46;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:5] Set("Local/6002@default-local-paging-84a9;2", "CDR(peerip)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:2] Set("Local/6001@default-local-paging-85da;2", "CDR(hangupcause)=16") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:4] Set("Local/6005@default-local-paging-85a8;2", "CDR(codec2)=slin") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:5] Set("Local/6006@default-local-paging-1d46;2", "CDR(peerip)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:6] Set("Local/6002@default-local-paging-84a9;2", "CDR(recvip)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:3] Set("Local/6001@default-local-paging-85da;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:7] Set("Local/6002@default-local-paging-84a9;2", "CDR(fromchan)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:4] Set("Local/6001@default-local-paging-85da;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:34:33] -- Executing [h@all-local-extension:1] Goto("Local/6000@default-local-paging-658c;2", "all-hangup,s,1") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:5] Set("Local/6005@default-local-paging-85a8;2", "CDR(peerip)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:8] Set("Local/6002@default-local-paging-84a9;2", "CDR(uri)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:6] Set("Local/6006@default-local-paging-1d46;2", "CDR(recvip)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:5] Set("Local/6001@default-local-paging-85da;2", "CDR(peerip)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:6] Set("Local/6005@default-local-paging-85a8;2", "CDR(recvip)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:7] Set("Local/6006@default-local-paging-1d46;2", "CDR(fromchan)=") in new stack [2012-01-09 09:34:33] -- Goto (all-hangup,s,1) [2012-01-09 09:34:33] -- Executing [s@all-hangup:1] Set("Local/6000@default-local-paging-658c;2", "CDR(hangupside)=") in new stack [2012-01-09 09:34:33] -- Executing [s@all-hangup:9] Set("Local/6002@default-local-paging-84a9;2", "CDR(useragent)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:6] Set("Local/6001@default-local-paging-85da;2", "CDR(recvip)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:7] Set("Local/6005@default-local-paging-85a8;2", "CDR(fromchan)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:8] Set("Local/6006@default-local-paging-1d46;2", "CDR(uri)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:2] Set("Local/6000@default-local-paging-658c;2", "CDR(hangupcause)=16") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:3] Set("Local/6000@default-local-paging-658c;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:7] Set("Local/6001@default-local-paging-85da;2", "CDR(fromchan)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:4] Set("Local/6000@default-local-paging-658c;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:8] Set("Local/6005@default-local-paging-85a8;2", "CDR(uri)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:10] Set("Local/6002@default-local-paging-84a9;2", "CDR(llp)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:9] Set("Local/6005@default-local-paging-85a8;2", "CDR(useragent)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:10] Set("Local/6005@default-local-paging-85a8;2", "CDR(llp)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:11] Set("Local/6005@default-local-paging-85a8;2", "CDR(rlp)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:12] Set("Local/6005@default-local-paging-85a8;2", "CDR(ljitt)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:11] Set("Local/6002@default-local-paging-84a9;2", "CDR(rlp)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:12] Set("Local/6002@default-local-paging-84a9;2", "CDR(ljitt)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:13] Set("Local/6002@default-local-paging-84a9;2", "CDR(rjitt)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:14] GotoIf("Local/6002@default-local-paging-84a9;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:9] Set("Local/6006@default-local-paging-1d46;2", "CDR(useragent)=") in new stack [2012-01-09 09:34:34] -- Goto (all-hangup,s,15) [2012-01-09 09:34:34] -- Executing [s@all-hangup:15] ResetCDR("Local/6002@default-local-paging-84a9;2", "w") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:8] Set("Local/6001@default-local-paging-85da;2", "CDR(uri)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:5] Set("Local/6000@default-local-paging-658c;2", "CDR(peerip)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:16] NoCDR("Local/6002@default-local-paging-84a9;2", "") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:10] Set("Local/6006@default-local-paging-1d46;2", "CDR(llp)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:13] Set("Local/6005@default-local-paging-85a8;2", "CDR(rjitt)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:6] Set("Local/6000@default-local-paging-658c;2", "CDR(recvip)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:7] Set("Local/6000@default-local-paging-658c;2", "CDR(fromchan)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:8] Set("Local/6000@default-local-paging-658c;2", "CDR(uri)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:9] Set("Local/6000@default-local-paging-658c;2", "CDR(useragent)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:10] Set("Local/6000@default-local-paging-658c;2", "CDR(llp)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:11] Set("Local/6000@default-local-paging-658c;2", "CDR(rlp)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:12] Set("Local/6000@default-local-paging-658c;2", "CDR(ljitt)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:13] Set("Local/6000@default-local-paging-658c;2", "CDR(rjitt)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:14] GotoIf("Local/6000@default-local-paging-658c;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:34:34] -- Goto (all-hangup,s,15) [2012-01-09 09:34:34] -- Executing [s@all-hangup:15] ResetCDR("Local/6000@default-local-paging-658c;2", "w") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:16] NoCDR("Local/6000@default-local-paging-658c;2", "") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:17] Hangup("Local/6000@default-local-paging-658c;2", "") in new stack [2012-01-09 09:34:34] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6000@default-local-paging-658c;2' [2012-01-09 09:34:34] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:34] -- Executing [s@all-hangup:9] Set("Local/6001@default-local-paging-85da;2", "CDR(useragent)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:10] Set("Local/6001@default-local-paging-85da;2", "CDR(llp)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:11] Set("Local/6001@default-local-paging-85da;2", "CDR(rlp)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:12] Set("Local/6001@default-local-paging-85da;2", "CDR(ljitt)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:13] Set("Local/6001@default-local-paging-85da;2", "CDR(rjitt)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:11] Set("Local/6006@default-local-paging-1d46;2", "CDR(rlp)=") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:17] Hangup("Local/6002@default-local-paging-84a9;2", "") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:14] GotoIf("Local/6005@default-local-paging-85a8;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:12] Set("Local/6006@default-local-paging-1d46;2", "CDR(ljitt)=") in new stack [2012-01-09 09:34:34] -- Incoming call: Got SIP response 500 "CSeq Number Out of order" back from 192.168.30.152:5060 [2012-01-09 09:34:34] -- Executing [s@all-hangup:14] GotoIf("Local/6001@default-local-paging-85da;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:34:34] -- Goto (all-hangup,s,15) [2012-01-09 09:34:34] -- Executing [s@all-hangup:15] ResetCDR("Local/6005@default-local-paging-85a8;2", "w") in new stack [2012-01-09 09:34:34] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6002@default-local-paging-84a9;2' [2012-01-09 09:34:34] -- Executing [h@all-local-extension:1] Goto("Local/6003@default-local-paging-364a;2", "all-hangup,s,1") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:16] NoCDR("Local/6005@default-local-paging-85a8;2", "") in new stack [2012-01-09 09:34:34] -- Goto (all-hangup,s,1) [2012-01-09 09:34:34] -- Executing [s@all-hangup:1] Set("Local/6003@default-local-paging-364a;2", "CDR(hangupside)=") in new stack [2012-01-09 09:34:34] -- Goto (all-hangup,s,15) [2012-01-09 09:34:34] -- Executing [s@all-hangup:15] ResetCDR("Local/6001@default-local-paging-85da;2", "w") in new stack [2012-01-09 09:34:34] -- Executing [s@all-hangup:17] Hangup("Local/6005@default-local-paging-85a8;2", "") in new stack [2012-01-09 09:34:35] -- Executing [s@all-hangup:13] Set("Local/6006@default-local-paging-1d46;2", "CDR(rjitt)=") in new stack [2012-01-09 09:34:35] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6005@default-local-paging-85a8;2' [2012-01-09 09:34:35] -- Executing [s@all-hangup:14] GotoIf("Local/6006@default-local-paging-1d46;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:34:35] -- Executing [s@all-hangup:16] NoCDR("Local/6001@default-local-paging-85da;2", "") in new stack [2012-01-09 09:34:35] -- Executing [s@all-hangup:2] Set("Local/6003@default-local-paging-364a;2", "CDR(hangupcause)=16") in new stack [2012-01-09 09:34:35] -- Goto (all-hangup,s,15) [2012-01-09 09:34:35] -- Executing [s@all-hangup:15] ResetCDR("Local/6006@default-local-paging-1d46;2", "w") in new stack [2012-01-09 09:34:35] -- Executing [s@all-hangup:17] Hangup("Local/6001@default-local-paging-85da;2", "") in new stack [2012-01-09 09:34:35] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer 'siptrunk' is now Reachable. (1866ms / 2000ms) [2012-01-09 09:34:35] -- Executing [s@all-hangup:3] Set("Local/6003@default-local-paging-364a;2", "CDR(codec1)=g722") in new stack [2012-01-09 09:34:35] -- Executing [s@all-hangup:4] Set("Local/6003@default-local-paging-364a;2", "CDR(codec2)=g722") in new stack [2012-01-09 09:34:35] -- Executing [s@all-hangup:5] Set("Local/6003@default-local-paging-364a;2", "CDR(peerip)=") in new stack [2012-01-09 09:34:35] -- Executing [s@all-hangup:6] Set("Local/6003@default-local-paging-364a;2", "CDR(recvip)=") in new stack [2012-01-09 09:34:35] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6001@default-local-paging-85da;2' [2012-01-09 09:34:35] -- Executing [s@all-hangup:7] Set("Local/6003@default-local-paging-364a;2", "CDR(fromchan)=") in new stack [2012-01-09 09:34:35] -- Executing [s@all-hangup:16] NoCDR("Local/6006@default-local-paging-1d46;2", "") in new stack [2012-01-09 09:34:35] -- Executing [s@all-hangup:17] Hangup("Local/6006@default-local-paging-1d46;2", "") in new stack [2012-01-09 09:34:35] -- Executing [s@all-hangup:8] Set("Local/6003@default-local-paging-364a;2", "CDR(uri)=") in new stack [2012-01-09 09:34:35] == Spawn extension (all-local-extension, paging, 5) exited non-zero on 'Local/6000@default-local-paging-658c;2' [2012-01-09 09:34:36] == Spawn extension (all-local-extension, paging, 5) exited non-zero on 'Local/6002@default-local-paging-84a9;2' [2012-01-09 09:34:36] -- Executing [s@all-hangup:9] Set("Local/6003@default-local-paging-364a;2", "CDR(useragent)=") in new stack [2012-01-09 09:34:36] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6006@default-local-paging-1d46;2' [2012-01-09 09:34:36] -- Executing [s@all-hangup:10] Set("Local/6003@default-local-paging-364a;2", "CDR(llp)=") in new stack [2012-01-09 09:34:36] == Spawn extension (all-local-extension, paging, 5) exited non-zero on 'Local/6005@default-local-paging-85a8;2' [2012-01-09 09:34:36] -- Executing [s@all-hangup:11] Set("Local/6003@default-local-paging-364a;2", "CDR(rlp)=") in new stack [2012-01-09 09:34:36] -- Executing [s@all-hangup:12] Set("Local/6003@default-local-paging-364a;2", "CDR(ljitt)=") in new stack [2012-01-09 09:34:36] -- Executing [s@all-hangup:13] Set("Local/6003@default-local-paging-364a;2", "CDR(rjitt)=") in new stack [2012-01-09 09:34:36] -- Executing [s@all-hangup:14] GotoIf("Local/6003@default-local-paging-364a;2", "0?all-faxnotify,s,1:15") in new stack [2012-01-09 09:34:36] -- Goto (all-hangup,s,15) [2012-01-09 09:34:36] -- Executing [s@all-hangup:15] ResetCDR("Local/6003@default-local-paging-364a;2", "w") in new stack [2012-01-09 09:34:36] -- Executing [s@all-hangup:16] NoCDR("Local/6003@default-local-paging-364a;2", "") in new stack [2012-01-09 09:34:36] -- Executing [s@all-hangup:17] Hangup("Local/6003@default-local-paging-364a;2", "") in new stack [2012-01-09 09:34:36] == Spawn extension (all-hangup, s, 17) exited non-zero on 'Local/6003@default-local-paging-364a;2' [2012-01-09 09:34:36] == Spawn extension (all-local-extension, paging, 5) exited non-zero on 'Local/6001@default-local-paging-85da;2' [2012-01-09 09:34:36] == Spawn extension (all-local-extension, paging, 5) exited non-zero on 'Local/6006@default-local-paging-1d46;2' [2012-01-09 09:34:36] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:37] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer 'switchvox' is now Reachable. (829ms / 2000ms) [2012-01-09 09:34:37] == Spawn extension (all-local-extension, paging, 5) exited non-zero on 'Local/6003@default-local-paging-364a;2' [2012-01-09 09:34:37] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:37] == Extension Changed 6000[default-local] new state Unavailable for Notify User 6001 [2012-01-09 09:34:37] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '6057' is now Reachable. (9ms / 20000ms) [2012-01-09 09:34:38] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '4001' is now Reachable. (29ms / 2000ms) [2012-01-09 09:34:38] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '2003' is now Reachable. (104ms / 2000ms) [2012-01-09 09:34:38] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '4000' is now Reachable. (105ms / 2000ms) [2012-01-09 09:34:39] NOTICE[9624]: res_musiconhold.c:675 monmp3thread: Request to schedule in the past?!?! [2012-01-09 09:34:41] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '5009' is now Reachable. (15ms / 2000ms) [2012-01-09 09:34:42] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '5010' is now Reachable. (14ms / 2000ms) [2012-01-09 09:34:42] == Extension Changed 5010[default-local] new state Idle for Notify User 6002 (queued) [2012-01-09 09:34:42] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '6002' is now Reachable. (15ms / 2000ms) [2012-01-09 09:34:42] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '6001' is now Reachable. (33ms / 2000ms) [2012-01-09 09:34:42] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '6005' is now Reachable. (12ms / 2000ms) [2012-01-09 09:34:44] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer 'allworx24x' is now Reachable. (3ms / 2000ms) [2012-01-09 09:34:44] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '6010' is now Reachable. (16ms / 2000ms) [2012-01-09 09:34:44] == Extension Changed 6010[default-local] new state Idle for Notify User 6001 [2012-01-09 09:34:45] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '6000' is now Reachable. (41ms / 2000ms) [2012-01-09 09:34:45] == Extension Changed 6000[default-local] new state Idle for Notify User 6001 [2012-01-09 09:34:45] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '6003' is now Reachable. (159ms / 2000ms) [2012-01-09 09:34:45] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '0762*100' is now Reachable. (15ms / 2000ms) [2012-01-09 09:34:45] NOTICE[9697]: chan_sip.c:20612 handle_response_peerpoke: Peer '6006' is now Reachable. (12ms / 2000ms)