logs [Mar 8 09:06:33] DEBUG[27802][C-00000001] pbx.c: Launching 'Verbose' [Mar 8 09:06:33] VERBOSE[27802][C-00000001] pbx.c: Executing [2662@staff:1] Verbose("PJSIP/688-00000000", ""Dialing 2662"") in new stack [Mar 8 09:06:33] VERBOSE[27802][C-00000001] app_verbose.c: Dialing 2662 [Mar 8 09:06:33] DEBUG[27802][C-00000001] pbx.c: Launching 'Dial' [Mar 8 09:06:33] VERBOSE[27802][C-00000001] pbx.c: Executing [2662@staff:2] Dial("PJSIP/688-00000000", "PJSIP/2662") in new stack [Mar 8 09:06:33] DEBUG[27802][C-00000001] channel.c: Channel 0x7f87e8006ac0 'PJSIP/2662-00000001' allocated [Mar 8 09:06:33] VERBOSE[27802][C-00000001] app_dial.c: Called PJSIP/2662 [Mar 8 09:06:33] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/2662-00000001 setting read format path: g722 -> g722 [Mar 8 09:06:33] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/688-00000000 setting write format path: g722 -> g722 [Mar 8 09:06:33] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/688-00000000 setting read format path: g722 -> g722 [Mar 8 09:06:33] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/2662-00000001 setting write format path: g722 -> g722 [Mar 8 09:06:33] DEBUG[27802][C-00000001] channel.c: PJSIP/688-00000000: Dropping redundant connected line update "FirstName LastName" <662>. [Mar 8 09:06:33] VERBOSE[27802][C-00000001] app_dial.c: PJSIP/2662-00000001 is ringing [Mar 8 09:06:33] VERBOSE[27802][C-00000001] app_dial.c: PJSIP/2662-00000001 is ringing [Mar 8 09:06:34] VERBOSE[27802][C-00000001] app_dial.c: PJSIP/2662-00000001 answered PJSIP/688-00000000 [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/2662-00000001 setting read format path: g722 -> g722 [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/688-00000000 setting write format path: g722 -> g722 [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/688-00000000 setting read format path: g722 -> g722 [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/2662-00000001 setting write format path: g722 -> g722 [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge_native_rtp.c: Bridge 'a83a8872-2155-4f83-a18c-861de13d8e4c' can not use native RTP bridge as two channels are required [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Mar 8 09:06:34] DEBUG[27802][C-00000001] dahdi/bridge_native_dahdi.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: Cannot use native DAHDI. Must have two channels. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology softmix has less preference than simple_bridge (10 <= 50). Skipping. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Chose bridge technology simple_bridge [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: calling simple_bridge technology constructor [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: calling simple_bridge technology start [Mar 8 09:06:34] DEBUG[27803][C-00000001] bridge_channel.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: 0x7f87e8002870(PJSIP/2662-00000001) is joining [Mar 8 09:06:34] DEBUG[27803][C-00000001] bridge_channel.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: pushing 0x7f87e8002870(PJSIP/2662-00000001) [Mar 8 09:06:34] VERBOSE[27803][C-00000001] bridge_channel.c: Channel PJSIP/2662-00000001 joined 'simple_bridge' basic-bridge [Mar 8 09:06:34] DEBUG[27803][C-00000001] bridge_native_rtp.c: Bridge 'a83a8872-2155-4f83-a18c-861de13d8e4c' can not use native RTP bridge as two channels are required [Mar 8 09:06:34] DEBUG[27803][C-00000001] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Mar 8 09:06:34] DEBUG[27803][C-00000001] dahdi/bridge_native_dahdi.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: Cannot use native DAHDI. Must have two channels. [Mar 8 09:06:34] DEBUG[27803][C-00000001] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Mar 8 09:06:34] DEBUG[27803][C-00000001] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Mar 8 09:06:34] DEBUG[27803][C-00000001] bridge.c: Bridge technology softmix does not have any capabilities we want. [Mar 8 09:06:34] DEBUG[27803][C-00000001] bridge.c: Chose bridge technology simple_bridge [Mar 8 09:06:34] DEBUG[27803][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c is already using the new technology. [Mar 8 09:06:34] DEBUG[27803][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: 0x7f87e8002870(PJSIP/2662-00000001) is joining simple_bridge technology [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge_channel.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: 0x7f87e8007d70(PJSIP/688-00000000) is joining [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge_channel.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: pushing 0x7f87e8007d70(PJSIP/688-00000000) [Mar 8 09:06:34] VERBOSE[27802][C-00000001] bridge_channel.c: Channel PJSIP/688-00000000 joined 'simple_bridge' basic-bridge [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge_native_rtp.c: Bridge 'a83a8872-2155-4f83-a18c-861de13d8e4c'. Checking compatability for channels 'PJSIP/2662-00000001' and 'PJSIP/688-00000000' [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge_native_rtp.c: Bridge 'a83a8872-2155-4f83-a18c-861de13d8e4c' can not use native RTP bridge as it was forbidden while getting details [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Mar 8 09:06:34] DEBUG[27802][C-00000001] dahdi/bridge_native_dahdi.c: Channel 'PJSIP/2662-00000001' is not DAHDI. [Mar 8 09:06:34] DEBUG[27802][C-00000001] dahdi/bridge_native_dahdi.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: Cannot use native DAHDI. Channel 'PJSIP/2662-00000001' not compatible. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology softmix does not have any capabilities we want. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Chose bridge technology simple_bridge [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c is already using the new technology. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: 0x7f87e8007d70(PJSIP/688-00000000) is joining simple_bridge technology [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/688-00000000 setting read format path: g722 -> g722 [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/2662-00000001 setting write format path: g722 -> g722 [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/2662-00000001 setting read format path: g722 -> g722 [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/688-00000000 setting write format path: g722 -> g722 [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Topology of PJSIP/688-00000000 already matches what is requested so ignoring topology change request [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Topology of PJSIP/688-00000000 already matches what is requested so ignoring topology change request [Mar 8 09:06:34] DEBUG[27803][C-00000001] channel.c: Dropping duplicate answer! [Mar 8 09:06:34] VERBOSE[27802][C-00000001] res_rtp_asterisk.c: 0x7f8778068070 -- Strict RTP switching to RTP target address 192.168.4.238:16538 as source [Mar 8 09:06:34] DEBUG[27802][C-00000001] chan_pjsip.c: Oooh, got a frame with format of alaw on channel 'PJSIP/688-00000000' when we're sending 'g722', switching to match [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/688-00000000 setting write format path: g722 -> alaw [Mar 8 09:06:34] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/688-00000000 setting read format path: g722 -> alaw [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge_native_rtp.c: Bridge 'a83a8872-2155-4f83-a18c-861de13d8e4c'. Checking compatability for channels 'PJSIP/2662-00000001' and 'PJSIP/688-00000000' [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge_native_rtp.c: Bridge 'a83a8872-2155-4f83-a18c-861de13d8e4c' can not use native RTP bridge as it was forbidden while getting details [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Mar 8 09:06:34] DEBUG[27802][C-00000001] dahdi/bridge_native_dahdi.c: Channel 'PJSIP/2662-00000001' is not DAHDI. [Mar 8 09:06:34] DEBUG[27802][C-00000001] dahdi/bridge_native_dahdi.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: Cannot use native DAHDI. Channel 'PJSIP/2662-00000001' not compatible. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge technology softmix does not have any capabilities we want. [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Chose bridge technology simple_bridge [Mar 8 09:06:34] DEBUG[27802][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c is already using the new technology. [Mar 8 09:06:34] DEBUG[27803][C-00000001] channel.c: Channel PJSIP/2662-00000001 changing write format from g722 to alaw, native formats (g722) [Mar 8 09:06:34] DEBUG[27803][C-00000001] channel.c: Channel PJSIP/2662-00000001 setting write format path: alaw -> g722 [Mar 8 09:06:34] DEBUG[27803][C-00000001] res_rtp_asterisk.c: Ooh, format changed from none to g722 [Mar 8 09:06:34] DEBUG[27803][C-00000001] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x7f8778076ea0' [Mar 8 09:06:34] VERBOSE[27803][C-00000001] res_rtp_asterisk.c: 0x7f8778077e50 -- Strict RTP switching to RTP target address 192.168.4.241:16444 as source [Mar 8 09:06:34] DEBUG[27802][C-00000001] res_rtp_asterisk.c: Ooh, format changed from none to alaw [Mar 8 09:06:39] VERBOSE[27803][C-00000001] res_rtp_asterisk.c: 0x7f8778077e50 -- Strict RTP learning complete - Locking on source address 192.168.4.241:16444 [Mar 8 09:06:39] VERBOSE[27802][C-00000001] res_rtp_asterisk.c: 0x7f8778068070 -- Strict RTP learning complete - Locking on source address 192.168.4.238:16538 [Mar 8 09:06:39] ERROR[27796] json.c: Error building JSON from '{s: o?, s: o, s: O?}': Expected format 's', got '?'. [Mar 8 09:06:39] ERROR[27796] : Got 9 backtrace records # 0: /usr/sbin/asterisk(ast_json_pack+0x8b) [0x4ed68b] # 1: /usr/sbin/asterisk() [0x52b00e] # 2: /usr/lib/asterisk/modules/res_hep_rtcp.so(+0x1063) [0x7f87a3dac063] # 3: /usr/sbin/asterisk() [0x5614a0] # 4: /usr/sbin/asterisk(ast_taskprocessor_execute+0x16e) [0x57ab6e] # 5: /usr/sbin/asterisk() [0x57b268] # 6: /usr/sbin/asterisk() [0x58c8bd] # 7: /lib64/libpthread.so.0(+0x7aa1) [0x7f881a0f4aa1] # 8: /lib64/libc.so.6(clone+0x6d) [0x7f88194d0bcd] [Mar 8 09:06:39] ERROR[27796] json.c: Error building JSON from '{s: o?, s: o, s: O?}': Expected format 's', got '?'. [Mar 8 09:06:39] ERROR[27796] : Got 9 backtrace records # 0: /usr/sbin/asterisk(ast_json_pack+0x8b) [0x4ed68b] # 1: /usr/sbin/asterisk() [0x52b00e] # 2: /usr/lib/asterisk/modules/res_hep_rtcp.so(+0x1063) [0x7f87a3dac063] # 3: /usr/sbin/asterisk() [0x5614a0] # 4: /usr/sbin/asterisk(ast_taskprocessor_execute+0x16e) [0x57ab6e] # 5: /usr/sbin/asterisk() [0x57b268] # 6: /usr/sbin/asterisk() [0x58c8bd] # 7: /lib64/libpthread.so.0(+0x7aa1) [0x7f881a0f4aa1] # 8: /lib64/libc.so.6(clone+0x6d) [0x7f88194d0bcd] [Mar 8 09:06:43] DEBUG[27802][C-00000001] bridge_channel.c: Setting 0x7f87e8007d70(PJSIP/688-00000000) state from:0 to:1 [Mar 8 09:06:43] DEBUG[27802][C-00000001] bridge_channel.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: pulling 0x7f87e8007d70(PJSIP/688-00000000) [Mar 8 09:06:43] VERBOSE[27802][C-00000001] bridge_channel.c: Channel PJSIP/688-00000000 left 'simple_bridge' basic-bridge [Mar 8 09:06:43] DEBUG[27802][C-00000001] bridge_channel.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: 0x7f87e8007d70(PJSIP/688-00000000) is leaving simple_bridge technology [Mar 8 09:06:43] DEBUG[27802][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: dissolving bridge with cause 16(Normal Clearing) [Mar 8 09:06:43] DEBUG[27802][C-00000001] bridge_channel.c: Setting 0x7f87e8002870(PJSIP/2662-00000001) state from:0 to:2 [Mar 8 09:06:43] DEBUG[27802][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: queueing action type:13 sub:1001 [Mar 8 09:06:43] DEBUG[27802][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c is dissolved, not performing smart bridge operation. [Mar 8 09:06:43] DEBUG[27803][C-00000001] bridge_channel.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: pulling 0x7f87e8002870(PJSIP/2662-00000001) [Mar 8 09:06:43] VERBOSE[27803][C-00000001] bridge_channel.c: Channel PJSIP/2662-00000001 left 'simple_bridge' basic-bridge [Mar 8 09:06:43] DEBUG[27802][C-00000001] bridge_channel.c: Bridge is returning 0x7f87e8007d70(PJSIP/688-00000000) to read format g722 [Mar 8 09:06:43] DEBUG[27803][C-00000001] bridge_channel.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: 0x7f87e8002870(PJSIP/2662-00000001) is leaving simple_bridge technology [Mar 8 09:06:43] DEBUG[27803][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c is dissolved, not performing smart bridge operation. [Mar 8 09:06:43] DEBUG[27802][C-00000001] channel.c: Channel PJSIP/688-00000000 setting read format path: alaw -> g722 [Mar 8 09:06:43] DEBUG[27803][C-00000001] bridge_channel.c: Bridge is returning 0x7f87e8002870(PJSIP/2662-00000001) to write format g722 [Mar 8 09:06:43] DEBUG[27803][C-00000001] channel.c: Channel PJSIP/2662-00000001 setting write format path: g722 -> g722 [Mar 8 09:06:43] DEBUG[27802][C-00000001] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Mar 8 09:06:43] DEBUG[27803][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: actually destroying basic bridge, nobody wants it anymore [Mar 8 09:06:43] DEBUG[27802][C-00000001] pbx.c: Spawn extension (staff,2662,2) exited non-zero on 'PJSIP/688-00000000' [Mar 8 09:06:43] VERBOSE[27802][C-00000001] pbx.c: Spawn extension (staff, 2662, 2) exited non-zero on 'PJSIP/688-00000000' [Mar 8 09:06:43] DEBUG[27803][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: calling basic bridge destructor [Mar 8 09:06:43] DEBUG[27802][C-00000001] channel.c: Soft-Hanging (0x10) up channel 'PJSIP/688-00000000' [Mar 8 09:06:43] DEBUG[27803][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: calling simple_bridge technology stop [Mar 8 09:06:43] DEBUG[27803][C-00000001] bridge.c: Bridge a83a8872-2155-4f83-a18c-861de13d8e4c: calling simple_bridge technology destructor [Mar 8 09:06:43] DEBUG[27803][C-00000001] channel.c: Channel 0x7f87e8006ac0 'PJSIP/2662-00000001' hanging up. Refs: 2 [Mar 8 09:06:43] DEBUG[27803][C-00000001] chan_pjsip.c: AST hangup cause 16 (no match found in PJSIP) [Mar 8 09:06:43] DEBUG[27802][C-00000001] channel.c: Channel 0x7f877806fd10 'PJSIP/688-00000000' hanging up. Refs: 2 [Mar 8 09:06:43] DEBUG[27802][C-00000001] chan_pjsip.c: AST hangup cause 16 (no match found in PJSIP)