[2015-09-11 17:30:05] VERBOSE[1418][C-00000003] netsock2.c: == Using SIP RTP TOS bits 184
[2015-09-11 17:30:05] VERBOSE[1418][C-00000003] netsock2.c: == Using SIP RTP CoS mark 5
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk-sip-Weiterleitung:1] Set("SIP/Weiterleitung-00000003", "GROUP()=OUT_3") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk-sip-Weiterleitung:2] Goto("SIP/Weiterleitung-00000003", "from-trunk,09977902100,1") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Goto (from-trunk,09977902100,1)
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk:1] Set("SIP/Weiterleitung-00000003", "__FROM_DID=09977902100") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk:2] Gosub("SIP/Weiterleitung-00000003", "sub-record-check,s,1(in,09977902100,dontcare)") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/Weiterleitung-00000003", "0?initialized") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:2] Set("SIP/Weiterleitung-00000003", "__REC_STATUS=INITIALIZED") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:3] Set("SIP/Weiterleitung-00000003", "NOW=1441985405") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:4] Set("SIP/Weiterleitung-00000003", "__DAY=11") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:5] Set("SIP/Weiterleitung-00000003", "__MONTH=09") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/Weiterleitung-00000003", "__YEAR=2015") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/Weiterleitung-00000003", "__TIMESTR=20150911-173005") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:8] Set("SIP/Weiterleitung-00000003", "__FROMEXTEN=unknown") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/Weiterleitung-00000003", "__MON_FMT=wav") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/Weiterleitung-00000003", "Recordings initialized") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/Weiterleitung-00000003", "0?Set(ARG3=dontcare)") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/Weiterleitung-00000003", "REC_POLICY_MODE_SAVE=") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/Weiterleitung-00000003", "0?Set(REC_STATUS=NO)") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/Weiterleitung-00000003", "2?checkaction") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Goto (sub-record-check,s,17)
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/Weiterleitung-00000003", "1?sub-record-check,in,1") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Goto (sub-record-check,in,1)
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [in@sub-record-check:1] NoOp("SIP/Weiterleitung-00000003", "Inbound Recording Check to 09977902100") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [in@sub-record-check:2] Set("SIP/Weiterleitung-00000003", "FROMEXTEN=unknown") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [in@sub-record-check:3] ExecIf("SIP/Weiterleitung-00000003", "11?Set(FROMEXTEN=01637292659)") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [in@sub-record-check:4] Gosub("SIP/Weiterleitung-00000003", "recordcheck,1(dontcare,in,09977902100)") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/Weiterleitung-00000003", "Starting recording check against dontcare") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/Weiterleitung-00000003", "dontcare") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("SIP/Weiterleitung-00000003", "") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [in@sub-record-check:5] Return("SIP/Weiterleitung-00000003", "") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk:3] Set("SIP/Weiterleitung-00000003", "CDR(did)=09977902100") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk:4] ExecIf("SIP/Weiterleitung-00000003", "0 ?Set(CALLERID(name)=01637292659)") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk:5] Set("SIP/Weiterleitung-00000003", "CHANNEL(musicclass)=default") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk:6] Set("SIP/Weiterleitung-00000003", "__MOHCLASS=default") in new stack
[2015-09-11 17:30:05] WARNING[4024][C-00000003] func_channel.c: Unknown or unavailable item requested: 'reversecharge'
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk:7] GotoIf("SIP/Weiterleitung-00000003", "0?macro-hangupcall") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk:8] Set("SIP/Weiterleitung-00000003", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk:9] Set("SIP/Weiterleitung-00000003", "CALLERPRES()=allowed_not_screened") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [09977902100@from-trunk:10] Goto("SIP/Weiterleitung-00000003", "ext-miscdests,1,1") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Goto (ext-miscdests,1,1)
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [1@ext-miscdests:1] NoOp("SIP/Weiterleitung-00000003", "MiscDest: Weiterleitung") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [1@ext-miscdests:2] Goto("SIP/Weiterleitung-00000003", "from-internal,099777359001,1") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Goto (from-internal,099777359001,1)
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [099777359001@from-internal:1] ResetCDR("SIP/Weiterleitung-00000003", "") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [099777359001@from-internal:2] NoCDR("SIP/Weiterleitung-00000003", "") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [099777359001@from-internal:3] Progress("SIP/Weiterleitung-00000003", "") in new stack
[2015-09-11 17:30:05] VERBOSE[4024][C-00000003] pbx.c: -- Executing [099777359001@from-internal:4] Wait("SIP/Weiterleitung-00000003", "1") in new stack
[2015-09-11 17:30:06] VERBOSE[4024][C-00000003] pbx.c: -- Executing [099777359001@from-internal:5] Progress("SIP/Weiterleitung-00000003", "") in new stack
[2015-09-11 17:30:06] VERBOSE[4024][C-00000003] pbx.c: -- Executing [099777359001@from-internal:6] Playback("SIP/Weiterleitung-00000003", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
[2015-09-11 17:30:06] VERBOSE[4024][C-00000003] file.c: -- <SIP/Weiterleitung-00000003> Playing 'silence/1.ulaw' (language 'en')
[2015-09-11 17:30:07] VERBOSE[4024][C-00000003] file.c: -- <SIP/Weiterleitung-00000003> Playing 'cannot-complete-as-dialed.ulaw' (language 'en')
[2015-09-11 17:30:09] VERBOSE[4024][C-00000003] file.c: -- <SIP/Weiterleitung-00000003> Playing 'check-number-dial-again.ulaw' (language 'en')
[2015-09-11 17:30:12] VERBOSE[4024][C-00000003] pbx.c: -- Executing [099777359001@from-internal:7] Wait("SIP/Weiterleitung-00000003", "1") in new stack
[2015-09-11 17:30:13] VERBOSE[4024][C-00000003] pbx.c: -- Executing [099777359001@from-internal:8] Congestion("SIP/Weiterleitung-00000003", "20") in new stack
[2015-09-11 17:30:13] WARNING[4024][C-00000003] channel.c: Prodding channel 'SIP/Weiterleitung-00000003' failed
[2015-09-11 17:30:13] VERBOSE[4024][C-00000003] pbx.c: == Spawn extension (from-internal, 099777359001, 8) exited non-zero on 'SIP/Weiterleitung-00000003'
[2015-09-11 17:30:13] VERBOSE[4024][C-00000003] pbx.c: -- Executing [h@from-internal:1] Hangup("SIP/Weiterleitung-00000003", "") in new stack
[2015-09-11 17:30:13] VERBOSE[4024][C-00000003] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/Weiterleitung-00000003'