[Nov 18 15:26:47] DEBUG[1370]: manager.c:6086 process_message: Running action 'Redirect' [Nov 18 15:26:47] DEBUG[1370]: channel.c:2550 ast_softhangup_nolock: Soft-Hanging (0x02) up channel 'PJSIP/tjoho-00000044' [Nov 18 15:26:47] DEBUG[1361][C-0000003d]: bridge_channel.c:288 ast_bridge_channel_leave_bridge_nolock: Setting 0x420f428(PJSIP/tjoho-00000044) state from:0 to:1 [Nov 18 15:26:47] DEBUG[1361][C-0000003d]: bridge_channel.c:1921 bridge_channel_internal_pull: Bridge 8849df21-58b8-43a7-9811-a44216792254: pulling 0x420f428(PJSIP/tjoho-00000044) -- Channel PJSIP/tjoho-00000044 left 'simple_bridge' basic-bridge <8849df21-58b8-43a7-9811-a44216792254> [Nov 18 15:26:47] DEBUG[1361][C-0000003d]: bridge_channel.c:1933 bridge_channel_internal_pull: Bridge 8849df21-58b8-43a7-9811-a44216792254: 0x420f428(PJSIP/tjoho-00000044) is leaving simple_bridge technology [Nov 18 15:26:47] DEBUG[1361][C-0000003d]: bridge.c:311 bridge_dissolve: Bridge 8849df21-58b8-43a7-9811-a44216792254: dissolving bridge with cause 16(Normal Clearing) [Nov 18 15:26:47] DEBUG[1361][C-0000003d]: bridge_channel.c:288 ast_bridge_channel_leave_bridge_nolock: Setting 0x43fb028(PJSIP/wid3-00000045) state from:0 to:2 [Nov 18 15:26:47] DEBUG[1361][C-0000003d]: bridge.c:272 bridge_queue_action_nodup: Bridge 8849df21-58b8-43a7-9811-a44216792254: queueing action type:13 sub:1001 [Nov 18 15:26:47] DEBUG[22071]: cdr.c:1266 cdr_object_finalize: Finalized CDR for PJSIP/tjoho-00000044 - start 1416320787.400235 answer 1416320791.793472 end 1416320807.890591 dispo ANSWERED [Nov 18 15:26:47] DEBUG[498]: manager.c:5646 match_filter: Examining AMI event: Event: BridgeLeave Privilege: call,all SystemName: s1 BridgeUniqueid: 8849df21-58b8-43a7-9811-a44216792254 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 1 Channel: PJSIP/tjoho-00000044 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 0123456789 CallerIDName: 0123456789 ConnectedLineNum: dial ConnectedLineName: Kalle Anka AccountCode: Context: routing-destinations Exten: dial Priority: 6 Uniqueid: s1-1416320787.1161 [Nov 18 15:26:47] DEBUG[1361][C-0000003d]: bridge.c:1040 smart_bridge_operation: Bridge 8849df21-58b8-43a7-9811-a44216792254 is dissolved, not performing smart bridge operation. [Nov 18 15:26:47] DEBUG[1368][C-0000003d]: bridge_channel.c:1921 bridge_channel_internal_pull: Bridge 8849df21-58b8-43a7-9811-a44216792254: pulling 0x43fb028(PJSIP/wid3-00000045) -- Channel PJSIP/wid3-00000045 left 'simple_bridge' basic-bridge <8849df21-58b8-43a7-9811-a44216792254> [Nov 18 15:26:47] DEBUG[1368][C-0000003d]: bridge_channel.c:1933 bridge_channel_internal_pull: Bridge 8849df21-58b8-43a7-9811-a44216792254: 0x43fb028(PJSIP/wid3-00000045) is leaving simple_bridge technology [Nov 18 15:26:47] DEBUG[1368][C-0000003d]: bridge.c:1040 smart_bridge_operation: Bridge 8849df21-58b8-43a7-9811-a44216792254 is dissolved, not performing smart bridge operation. [Nov 18 15:26:47] DEBUG[1368][C-0000003d]: channel.c:2699 ast_hangup: Hanging up channel 'PJSIP/wid3-00000045' [Nov 18 15:26:47] DEBUG[1368][C-0000003d]: chan_pjsip.c:1629 hangup_cause2sip: AST hangup cause 16 (no match found in PJSIP) [Nov 18 15:26:47] DEBUG[22083]: pjsip:0 : endpoint .Request msg BYE/cseq=11942 (tdta0x2b5aad0) created. [Nov 18 15:26:47] DEBUG[22083]: res_pjsip_session.c:1840 handle_outgoing_request: Method is BYE [Nov 18 15:26:47] DEBUG[498]: manager.c:5646 match_filter: Examining AMI event: Event: BridgeLeave Privilege: call,all SystemName: s1 BridgeUniqueid: 8849df21-58b8-43a7-9811-a44216792254 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 0 Channel: PJSIP/wid3-00000045 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: dial CallerIDName: Kalle Anka ConnectedLineNum: 0123456789 ConnectedLineName: 0123456789 AccountCode: Context: from-company_4 Exten: Priority: 1 Uniqueid: s1-1416320788.1162 [Nov 18 15:26:47] DEBUG[22083]: config.c:3496 ast_parse_arg: extract int from [60] in [-2147483648, 2147483647] gives [60](0) [Nov 18 15:26:47] DEBUG[22083]: config.c:3496 ast_parse_arg: extract int from [3600] in [-2147483648, 2147483647] gives [3600](0) [Nov 18 15:26:47] DEBUG[22083]: config.c:3496 ast_parse_arg: extract int from [0] in [-2147483648, 2147483647] gives [0](0) [Nov 18 15:26:47] DEBUG[22083]: config.c:3496 ast_parse_arg: extract int from [7200] in [-2147483648, 2147483647] gives [7200](0) [Nov 18 15:26:47] DEBUG[22083]: config.c:3496 ast_parse_arg: extract int from [2] in [-2147483648, 2147483647] gives [2](0) [Nov 18 15:26:47] DEBUG[22083]: config.c:3496 ast_parse_arg: extract int from [30] in [0, 86400] gives [30](0) [Nov 18 15:26:47] DEBUG[22083]: pjsip:0 : inv0x3059238 .Sending Request msg BYE/cseq=11942 (tdta0x2b5aad0) [Nov 18 15:26:47] DEBUG[22083]: pjsip:0 : dlg0x3059238 ..Sending Request msg BYE/cseq=11942 (tdta0x2b5aad0) [Nov 18 15:26:47] DEBUG[22083]: pjsip:0 : tsx0x3744288 ...Transaction created for Request msg BYE/cseq=11941 (tdta0x2b5aad0) [Nov 18 15:26:47] DEBUG[22083]: pjsip:0 : tsx0x3744288 ..Sending Request msg BYE/cseq=11941 (tdta0x2b5aad0) in state Null [Nov 18 15:26:47] DEBUG[22083]: pjsip:0 : sip_resolve.c ...Target 'Y.Y.Y.Y:51111' type=TLS resolved to 'Y.Y.Y.Y:51111' type=TLS (TLS transport) [Nov 18 15:26:47] DEBUG[22083]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'X.X.X.X:5093' into... [Nov 18 15:26:47] DEBUG[22083]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'X.X.X.X' and port '5093'. [Nov 18 15:26:47] DEBUG[22083]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'Y.Y.Y.Y:51111' into... [Nov 18 15:26:47] DEBUG[22083]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'Y.Y.Y.Y' and port '51111'. [Nov 18 15:26:47] DEBUG[22083]: pjsip:0 : tsx0x3744288 ...State changed from Null to Calling, event=TX_MSG [Nov 18 15:26:47] WARNING[1370]: channel.c:10376 ast_channel_move: Can't move channel. One or both is dead (Surrogate/PJSIP/wid3-00000045 <-- PJSIP/wid3-00000045) [Nov 18 15:26:47] DEBUG[1370]: channel.c:2699 ast_hangup: Hanging up channel 'Surrogate/PJSIP/wid3-00000045' [Nov 18 15:26:47] DEBUG[22083]: pjsip:0 : dlg0x3059238 ....Transaction tsx0x3744288 state changed to Calling [Nov 18 15:26:47] DEBUG[22083]: res_pjsip_session.c:1756 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Nov 18 15:26:47] DEBUG[22083]: res_pjsip_session.c:1767 __print_debug_details: The state change pertains to the session with wid3 [Nov 18 15:26:47] DEBUG[22083]: res_pjsip_session.c:1772 __print_debug_details: The inv session does NOT have an invite_tsx [Nov 18 15:26:47] DEBUG[22083]: res_pjsip_session.c:1775 __print_debug_details: The transaction involved in this state change is 0x3744288 [Nov 18 15:26:47] DEBUG[22088]: pjsip:0 : sip_endpoint.c Processing incoming message: Response msg 200/BYE/cseq=11941 (rdata0x4528a48) [Nov 18 15:26:47] DEBUG[22088]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'Y.Y.Y.Y:51111' into... [Nov 18 15:26:47] DEBUG[22088]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'Y.Y.Y.Y' and port '51111'. [Nov 18 15:26:47] DEBUG[22088]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'X.X.X.X:5093' into... [Nov 18 15:26:47] DEBUG[22088]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'X.X.X.X' and port '5093'. [Nov 18 15:26:47] DEBUG[22083]: res_pjsip_session.c:1776 __print_debug_details: The current transaction state is Calling [Nov 18 15:26:47] DEBUG[22083]: res_pjsip_session.c:1777 __print_debug_details: The transaction state change event is TX_MSG [Nov 18 15:26:47] DEBUG[22083]: res_pjsip_session.c:1781 __print_debug_details: The current inv state is CONFIRMED [Nov 18 15:26:47] DEBUG[22083]: res_pjsip_session.c:1867 handle_outgoing: Sending request [Nov 18 15:26:47] DEBUG[22083]: res_pjsip_session.c:1840 handle_outgoing_request: Method is BYE [Nov 18 15:26:47] DEBUG[22066]: devicestate.c:368 _ast_device_state: No provider found, checking channel drivers for Surrogate - PJSIP/wid3 [Nov 18 15:26:47] DEBUG[22066]: devicestate.c:464 do_state_change: Changing state for Surrogate/PJSIP/wid3 - state 0 (Unknown) [Nov 18 15:26:47] WARNING[1370]: pbx.c:9687 ast_async_goto: Unable to gain control of channel PJSIP/wid3-00000045 [Nov 18 15:26:47] DEBUG[1370]: manager.c:5646 match_filter: Examining AMI event: Event: BridgeLeave Privilege: call,all SystemName: s1 BridgeUniqueid: 8849df21-58b8-43a7-9811-a44216792254 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 1 Channel: PJSIP/tjoho-00000044 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 0123456789 CallerIDName: 0123456789 ConnectedLineNum: dial ConnectedLineName: Kalle Anka AccountCode: Context: routing-destinations Exten: dial Priority: 6 Uniqueid: s1-1416320787.1161 [Nov 18 15:26:47] DEBUG[1370]: manager.c:5646 match_filter: Examining AMI event: Event: BridgeLeave Privilege: call,all SystemName: s1 BridgeUniqueid: 8849df21-58b8-43a7-9811-a44216792254 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 0 Channel: PJSIP/wid3-00000045 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: dial CallerIDName: Kalle Anka ConnectedLineNum: 0123456789 ConnectedLineName: 0123456789 AccountCode: Context: from-company_4 Exten: Priority: 1 Uniqueid: s1-1416320788.1162 [Nov 18 15:26:47] DEBUG[498]: manager.c:5646 match_filter: Examining AMI event: Event: Newexten Privilege: call,all SystemName: s1 Channel: PJSIP/tjoho-00000044 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 0123456789 CallerIDName: 0123456789 ConnectedLineNum: dial ConnectedLineName: Kalle Anka AccountCode: Context: hold-bridge Exten: moh Priority: 1 Uniqueid: s1-1416320787.1161 Extension: moh Application: Dial AppData: PJSIP/wid3&SCCP/wid4,10 [Nov 18 15:26:47] DEBUG[1361][C-0000003d]: bridge.c:638 destroy_bridge: Bridge 8849df21-58b8-43a7-9811-a44216792254: actually destroying basic bridge, nobody wants it anymore [Nov 18 15:26:47] DEBUG[1361][C-0000003d]: bridge.c:663 destroy_bridge: Bridge 8849df21-58b8-43a7-9811-a44216792254: calling basic bridge destructor [Nov 18 15:26:47] DEBUG[1370]: tcptls.c:348 tcptls_stream_write: TCP socket error writing: Connection reset by peer [Nov 18 15:26:47] DEBUG[1370]: tcptls.c:348 tcptls_stream_write: TCP socket error writing: Broken pipe [Nov 18 15:26:47] ERROR[1370]: utils.c:1449 ast_careful_fwrite: fwrite() returned error: Broken pipe