[2019-05-14 09:15:40] VERBOSE[39826][C-0000434b] netsock2.c: Using SIP RTP CoS mark 5
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] pbx_realtime.c: Executing [MASKED@from-voip-provider:1] NoOp("SIP/siptrunk-000074f4", "Inbound Call")
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] pbx_realtime.c: Executing [MASKEDNUMBER@from-voip-provider:2] Set("SIP/siptrunk-000074f4", "CDR(type)=inbound")
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] pbx_realtime.c: Executing [MASKEDNUMBER@from-voip-provider:3] Set("SIP/siptrunk-000074f4", "XCALLY-MOTION-ANSWER=0")
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] pbx_realtime.c: Executing [MASKEDNUMBER@from-voip-provider:4] Set("SIP/siptrunk-000074f4", "XCALLY-MOTION-PROGRESS=0")
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] pbx_realtime.c: Executing [MASKEDNUMBER@from-voip-provider:5] Set("SIP/siptrunk-000074f4", "MONITOR_OPTIONS=ab")
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] pbx_realtime.c: Executing [MASKEDNUMBER@from-voip-provider:6] Set("SIP/siptrunk-000074f4", "CHANNEL(language)=se")
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] pbx_realtime.c: Executing [MASKEDNUMBER@from-voip-provider:7] AGI("SIP/siptrunk-000074f4", "agi://127.0.0.1/square,C")
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: START CALLY SQUARE
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: Answer
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: AGI Script Executing Application: (AGI) Options: (agi://127.0.0.1/square,GENERAL_cleanDNID,C)
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,GENERAL_cleanDNID,C: START CALLY SQUARE
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,GENERAL_cleanDNID,C: Answer
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,GENERAL_cleanDNID,C: math MASKEDNUMBER.substr(0,1)
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,GENERAL_cleanDNID,C: EVAL CONDITION 0 == 0 true
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,GENERAL_cleanDNID,C: math MASKEDNUMBER.substr(1,100)
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,GENERAL_cleanDNID,C: end
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,GENERAL_cleanDNID,C: GOTO from-voip-provider MASKEDNUMBER 8
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: <SIP/siptrunk-000074f4>AGI Script agi://127.0.0.1/square completed, returning 0
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: Executed command AGI GENERAL_cleanDNID
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: Always open
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME *,*,12-28,feb false
[2019-05-14 09:15:40] VERBOSE[55542][C-0000434b] file.c: <SIP/siptrunk-000074f4> Playing '/var/opt/motion2/server/files/sounds/converted/ec_Meny_20190401-1554099896078.slin' (language 'se')
[2019-05-14 09:15:57] DTMF[55542][C-0000434b] channel.c: DTMF begin '0' received on SIP/siptrunk-000074f4
[2019-05-14 09:15:57] DTMF[55542][C-0000434b] channel.c: DTMF begin ignored '0' on SIP/siptrunk-000074f4
[2019-05-14 09:15:57] DTMF[55542][C-0000434b] channel.c: DTMF end '0' received on SIP/siptrunk-000074f4, duration 140 ms
[2019-05-14 09:15:57] DTMF[55542][C-0000434b] channel.c: DTMF end passthrough '0' on SIP/siptrunk-000074f4
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME 09:00-18:00,mon-fri,*,* true
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME 15:00-23:00,thu,18,apr false
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME *,fri,19,apr false
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME *,mon,22,apr false
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME 15:00-23:00,tue,30,apr false
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME *,wed,1,may false
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME *,thu,30,may false
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME *,thu,6,jun false
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: sh command: node /usr/src/ag-queue-depth/con_get_queue_depth.js EC_Kampanj result on variable: VAR2
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME 16:45-21:00,mon-fri,*,* false
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: IFTIME 14:00-23:00,*,18,apr false
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: EVAL CONDITION Number(0)  >= Number(100) false
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: sh command: echo Samtal
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_agi.c: AGI Script Executing Application: (QUEUE) Options: (EC_Kampanj,xX,,,28800,,,MMSUB,,)
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] res_musiconhold.c: Started music on hold, class 'Ringback', on channel 'SIP/siptrunk-000074f4'
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] netsock2.c: Using SIP RTP CoS mark 5
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] app_queue.c: Called SIP/agent97
[2019-05-14 09:15:57] VERBOSE[55542][C-0000434b] app_queue.c: SIP/agent97-000074fe is ringing
[2019-05-14 09:16:02] VERBOSE[55542][C-0000434b] app_queue.c: SIP/agent97-000074fe answered SIP/siptrunk-000074f4
[2019-05-14 09:16:02] VERBOSE[55542][C-0000434b] res_musiconhold.c: Stopped music on hold on SIP/siptrunk-000074f4
[2019-05-14 09:16:02] VERBOSE[55542][C-0000434b] app_stack.c: SIP/agent97-000074fe Internal Gosub(MMSUB,s,1) start
[2019-05-14 09:16:02] ERROR[55542][C-0000434b] app_stack.c: Attempt to reach a non-existent destination for Gosub: (Context:MMSUB, Extension:s, Priority:1)
[2019-05-14 09:16:02] VERBOSE[55970][C-0000434b] bridge_channel.c: Channel SIP/agent97-000074fe joined 'simple_bridge' basic-bridge <0b50880b-3f3b-4432-acd8-26de5db5e79b>
[2019-05-14 09:16:02] VERBOSE[55542][C-0000434b] bridge_channel.c: Channel SIP/siptrunk-000074f4 joined 'simple_bridge' basic-bridge <0b50880b-3f3b-4432-acd8-26de5db5e79b>
[2019-05-14 09:23:20] DTMF[55970][C-0000434b] channel.c: DTMF begin '9' received on SIP/agent97-000074fe
[2019-05-14 09:23:20] DTMF[55970][C-0000434b] channel.c: DTMF begin passthrough '9' on SIP/agent97-000074fe
[2019-05-14 09:23:21] DTMF[55970][C-0000434b] channel.c: DTMF end '9' received on SIP/agent97-000074fe, duration 200 ms
[2019-05-14 09:23:21] DTMF[55970][C-0000434b] channel.c: DTMF end accepted with begin '9' on SIP/agent97-000074fe
[2019-05-14 09:23:21] DTMF[55970][C-0000434b] channel.c: DTMF end passthrough '9' on SIP/agent97-000074fe
[2019-05-14 09:23:21] DTMF[55970][C-0000434b] channel.c: DTMF begin '2' received on SIP/agent97-000074fe
[2019-05-14 09:23:21] DTMF[55970][C-0000434b] channel.c: DTMF begin passthrough '2' on SIP/agent97-000074fe
[2019-05-14 09:23:21] DTMF[55970][C-0000434b] channel.c: DTMF end '2' received on SIP/agent97-000074fe, duration 200 ms
[2019-05-14 09:23:21] DTMF[55970][C-0000434b] channel.c: DTMF end accepted with begin '2' on SIP/agent97-000074fe
[2019-05-14 09:23:21] DTMF[55970][C-0000434b] channel.c: DTMF end passthrough '2' on SIP/agent97-000074fe
[2019-05-14 09:23:22] DTMF[55970][C-0000434b] channel.c: DTMF begin '4' received on SIP/agent97-000074fe
[2019-05-14 09:23:22] DTMF[55970][C-0000434b] channel.c: DTMF begin passthrough '4' on SIP/agent97-000074fe
[2019-05-14 09:23:22] DTMF[55970][C-0000434b] channel.c: DTMF end '4' received on SIP/agent97-000074fe, duration 200 ms
[2019-05-14 09:23:22] DTMF[55970][C-0000434b] channel.c: DTMF end accepted with begin '4' on SIP/agent97-000074fe
[2019-05-14 09:23:22] DTMF[55970][C-0000434b] channel.c: DTMF end passthrough '4' on SIP/agent97-000074fe
[2019-05-14 09:23:22] DTMF[55970][C-0000434b] channel.c: DTMF begin '2' received on SIP/agent97-000074fe
[2019-05-14 09:23:22] DTMF[55970][C-0000434b] channel.c: DTMF begin passthrough '2' on SIP/agent97-000074fe
[2019-05-14 09:23:23] DTMF[55970][C-0000434b] channel.c: DTMF end '2' received on SIP/agent97-000074fe, duration 200 ms
[2019-05-14 09:23:23] DTMF[55970][C-0000434b] channel.c: DTMF end accepted with begin '2' on SIP/agent97-000074fe
[2019-05-14 09:23:23] DTMF[55970][C-0000434b] channel.c: DTMF end passthrough '2' on SIP/agent97-000074fe
[2019-05-14 09:23:23] VERBOSE[55542][C-0000434b] res_musiconhold.c: Started music on hold, class 'default', on channel 'SIP/siptrunk-000074f4'
[2019-05-14 09:23:24] VERBOSE[39826][C-000043aa] netsock2.c: Using SIP RTP CoS mark 5
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:1] NoOp("SIP/agent97-000075b7", "Internal Call")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:2] Set("SIP/agent97-000075b7", "CDR(type)=internal")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:3] Set("SIP/agent97-000075b7", "XCALLY-MOTION-ANSWER=0")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:4] Set("SIP/agent97-000075b7", "XCALLY-MOTION-PROGRESS=0")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:5] Set("SIP/agent97-000075b7", "MONITOR_OPTIONS=ab")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:6] GotoIfTime("SIP/agent97-000075b7", "09:00-18:00,mon-fri,*,*?from-sip,9242,7:from-sip,9242,8")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_builtins.c: Goto (from-sip,9242,7)
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:7] Set("SIP/agent97-000075b7", "CHANNEL(language)=se")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:8] GotoIfTime("SIP/agent97-000075b7", "09:00-18:00,mon-fri,*,*?from-sip,9242,9:from-sip,9242,12")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_builtins.c: Goto (from-sip,9242,9)
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:9] ExecIf("SIP/agent97-000075b7", "1?Answer")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:10] Set("SIP/agent97-000075b7", "XCALLY-MOTION-ANSWER=1")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] pbx_realtime.c: Executing [9242@from-sip:11] Queue("SIP/agent97-000075b7", "EC_Kundservice,xX,,,28800,,,,,")
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] res_musiconhold.c: Started music on hold, class 'Ringback', on channel 'SIP/agent97-000075b7'
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] netsock2.c: Using SIP RTP CoS mark 5
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] app_queue.c: Called SIP/agent101
[2019-05-14 09:23:24] VERBOSE[63090][C-000043aa] app_queue.c: SIP/agent101-000075b8 is ringing
[2019-05-14 09:23:29] VERBOSE[63090][C-000043aa] app_queue.c: SIP/agent101-000075b8 answered SIP/agent97-000075b7
[2019-05-14 09:23:29] VERBOSE[63090][C-000043aa] res_musiconhold.c: Stopped music on hold on SIP/agent97-000075b7
[2019-05-14 09:23:29] VERBOSE[55542][C-0000434b] res_musiconhold.c: Stopped music on hold on SIP/siptrunk-000074f4
[2019-05-14 09:23:29] VERBOSE[55970][C-0000434b] bridge_channel.c: Channel SIP/agent97-000074fe left 'simple_bridge' basic-bridge <0b50880b-3f3b-4432-acd8-26de5db5e79b>
[2019-05-14 09:23:29] VERBOSE[55542][C-0000434b] bridge_channel.c: Channel SIP/siptrunk-000074f4 left 'simple_bridge' basic-bridge <0b50880b-3f3b-4432-acd8-26de5db5e79b>
[2019-05-14 09:23:29] VERBOSE[63158][C-000043aa] bridge_channel.c: Channel SIP/agent101-000075b8 joined 'simple_bridge' basic-bridge <ab7c1510-bfff-4f28-862e-0421c87b8d50>
[2019-05-14 09:23:29] VERBOSE[63090][C-000043aa] bridge_channel.c: Channel SIP/agent97-000075b7 joined 'simple_bridge' basic-bridge <ab7c1510-bfff-4f28-862e-0421c87b8d50>
[2019-05-14 09:23:29] VERBOSE[55542][C-0000434b] res_agi.c: agi://127.0.0.1/square,C: GOTO from-voip-provider MASKEDNUMBER 8
[2019-05-14 09:23:29] VERBOSE[55542][C-0000434b] res_agi.c: <SIP/agent97-000075b7>AGI Script agi://127.0.0.1/square completed, returning 4
[2019-05-14 09:23:29] VERBOSE[55542][C-0000434b] pbx.c: Spawn extension (from-voip-provider, MASKEDNUMBER, 7) exited non-zero on 'SIP/agent97-000075b7'