[asterisk-bugs] [JIRA] (ASTERISK-29907) res_pjsip, app_confbridge: Video call through ConfBridge with normal endpoints causes infinite loop/crash
N A (JIRA)
noreply at issues.asterisk.org
Fri Jun 17 19:51:49 CDT 2022
[ https://issues.asterisk.org/jira/browse/ASTERISK-29907?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=259526#comment-259526 ]
N A commented on ASTERISK-29907:
--------------------------------
Here is another log with core debug level 10 (plus a few added log messages):
{noformat}
$ cat full.txt | grep -v "pjsip_resolver" | grep -v " pjsip_message_filter" | grep -v "res_pjsip_session" | grep -v "pjsip_message_filter" | grep -v "acl.c" | head -250
[2022-06-18 00:38:35] DEBUG[12849]: manager.c:6201 match_filter: Examining AMI event:
Event: BridgeVideoSourceUpdate
Privilege: call,all
Timestamp: 1655512715.894109
SystemName: REDACTED
BridgeUniqueid: 0b781f1d-61a5-4c04-8b33-051e0e93617a
BridgeType: base
BridgeTechnology: softmix
BridgeCreator: ConfBridge
BridgeName: vidtest2
BridgeNumChannels: 3
BridgeVideoSourceMode: talker
BridgeVideoSource: REDACTED-1655512697.5
BridgePreviousVideoSource:
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: stasis_bridges.c:270 bridge_snapshot_update_dtor: Update: 0x7f3cd804b778 Old: 0b781f1d-61a5-4c04-8b33-051e0e93617a New: 0b781f1d-61a5-4c04-8b33-051e0e93617a
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge.c:3927 ast_bridge_update_talker_src_video_mode: Video update
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12916][C-00000001]: channel.c:5833 set_format: Channel Local/1 at vidtest-00000000;2 setting write format path: slin -> slin
[2022-06-18 00:38:35] DEBUG[12916][C-00000001]: channel.c:3211 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: res_rtp_asterisk.c:4972 rtp_raw_write: (0x7f3cf0138bf0) RTP video difference is 1656090, ms is 1 (90), pred/ts/samples 6090/1662180/0
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: res_rtp_asterisk.c:5376 ast_rtp_write: (0x7f3cf00a86c0) RTP ooh, format changed from none to ulaw
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: res_rtp_asterisk.c:5116 rtp_raw_write: (0x7f3cf00a86c0) RTCP starting transmission
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:503 distributor: Searching for serializer associated with dialog dlg0x7f3ce81bb3d8 for Response msg 200/INFO/cseq=25190 (rdata0x7f3cdc0b09a8)
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:511 distributor: Found serializer pjsip/outsess/tsip-00000121 associated with dialog dlg0x7f3ce81bb3d8
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:503 distributor: Searching for serializer associated with dialog dlg0x7f3cf01111b8 for Response msg 200/INFO/cseq=6521 (rdata0x7f3cdc1f7c88)
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:511 distributor: Found serializer pjsip/distributor-00000077 associated with dialog dlg0x7f3cf01111b8
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] > 0x7f3cf00aa4f0 -- Strict RTP switching source address to REDACTED:4000
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: res_rtp_asterisk.c:8541 ast_rtp_remote_address_set: (0x7f3cf00a86c0) RTCP setting address on RTP instance
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: res_rtp_asterisk.c:4972 rtp_raw_write: (0x7f3cf0138bf0) RTP video difference is 1652880, ms is 33 (2970), pred/ts/samples 1664970/12090/6000
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:503 distributor: Searching for serializer associated with dialog dlg0x7f3ce81bb3d8 for Response msg 200/INFO/cseq=25191 (rdata0x7f3cdc0b09a8)
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:511 distributor: Found serializer pjsip/outsess/tsip-00000121 associated with dialog dlg0x7f3ce81bb3d8
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:503 distributor: Searching for serializer associated with dialog dlg0x7f3ce81bb3d8 for Response msg 200/INFO/cseq=25192 (rdata0x7f3cdc0b09a8)
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:511 distributor: Found serializer pjsip/outsess/tsip-00000121 associated with dialog dlg0x7f3ce81bb3d8
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge.c:3903 ast_bridge_update_talker_src_video_mode: Video update
[2022-06-18 00:38:35] DEBUG[12915][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12915][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] > Video source in bridge 'vidtest' (1c132f00-9349-4b7c-9868-6ee1aff10454) is now 'Local/1 at vidtest-00000000;1' (REDACTED-1655512697.1)
[2022-06-18 00:38:35] DEBUG[12915][C-00000001]: stasis_bridges.c:290 bridge_snapshot_update_create: Update: 0x7f3ce81de928 Old: 1c132f00-9349-4b7c-9868-6ee1aff10454 New: 1c132f00-9349-4b7c-9868-6ee1aff10454
[2022-06-18 00:38:35] DEBUG[12849]: manager.c:6201 match_filter: Examining AMI event:
Event: BridgeVideoSourceUpdate
Privilege: call,all
Timestamp: 1655512715.954010
SystemName: REDACTED
BridgeUniqueid: 1c132f00-9349-4b7c-9868-6ee1aff10454
BridgeType: base
BridgeTechnology: softmix
BridgeCreator: ConfBridge
BridgeName: vidtest
BridgeNumChannels: 3
BridgeVideoSourceMode: talker
BridgeVideoSource: REDACTED-1655512697.1
BridgePreviousVideoSource: REDACTED-1655512697.0
[2022-06-18 00:38:35] DEBUG[12915][C-00000001]: stasis_bridges.c:270 bridge_snapshot_update_dtor: Update: 0x7f3ce81de928 Old: 1c132f00-9349-4b7c-9868-6ee1aff10454 New: 1c132f00-9349-4b7c-9868-6ee1aff10454
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12913][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/tsip-00000001: Indicated Video Update
[2022-06-18 00:38:35] NOTICE[12923][C-00000001]: chan_pjsip.c:1772 chan_pjsip_indicate: Video updates: 2
[2022-06-18 00:38:35] DEBUG[12923][C-00000001]: chan_pjsip.c:1928 chan_pjsip_indicate: PJSIP/tsip-00000001
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12916][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:503 distributor: Searching for serializer associated with dialog dlg0x7f3ce81bb3d8 for Response msg 200/INFO/cseq=25193 (rdata0x7f3cdc0b09a8)
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:511 distributor: Found serializer pjsip/outsess/tsip-00000121 associated with dialog dlg0x7f3ce81bb3d8
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:503 distributor: Searching for serializer associated with dialog dlg0x7f3ce81bb3d8 for Response msg 200/INFO/cseq=25194 (rdata0x7f3cdc0b09a8)
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:511 distributor: Found serializer pjsip/outsess/tsip-00000121 associated with dialog dlg0x7f3ce81bb3d8
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:503 distributor: Searching for serializer associated with dialog dlg0x7f3ce81bb3d8 for Response msg 200/INFO/cseq=25195 (rdata0x7f3cdc0b09a8)
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:511 distributor: Found serializer pjsip/outsess/tsip-00000121 associated with dialog dlg0x7f3ce81bb3d8
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:503 distributor: Searching for serializer associated with dialog dlg0x7f3cf01111b8 for Response msg 200/INFO/cseq=6522 (rdata0x7f3cdc1f7c88)
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:511 distributor: Found serializer pjsip/distributor-00000077 associated with dialog dlg0x7f3cf01111b8
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:503 distributor: Searching for serializer associated with dialog dlg0x7f3cf01111b8 for Response msg 200/INFO/cseq=6523 (rdata0x7f3cdc1f7c88)
[2022-06-18 00:38:35] DEBUG[12836]: res_pjsip/pjsip_distributor.c:511 distributor: Found serializer pjsip/distributor-00000077 associated with dialog dlg0x7f3cf01111b8
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] NOTICE[12915][C-00000001]: bridge_softmix.c:1246 softmix_bridge_write_control: Queuing null to everyone
[2022-06-18 00:38:35] DEBUG[12913][C-00000001]: chan_pjsip.c:1717 chan_pjsip_indicate: PJSIP/ATAxMicroSIP1-00000000: Indicated Video Update
{noformat}
> res_pjsip, app_confbridge: Video call through ConfBridge with normal endpoints causes infinite loop/crash
> ---------------------------------------------------------------------------------------------------------
>
> Key: ASTERISK-29907
> URL: https://issues.asterisk.org/jira/browse/ASTERISK-29907
> Project: Asterisk
> Issue Type: Bug
> Security Level: None
> Components: Applications/app_confbridge
> Affects Versions: 18.9.0
> Environment: Debian 10
> Reporter: N A
> Assignee: Unassigned
> Attachments: core-asterisk-running-2022-06-17T23-24-08Z-full.txt, deadlock.txt, hangup_quickly.txt
>
>
> Something seems to be very wrong with the bridging logic for handing video calls.
> When making a direct call between two endpoints, including going through local channels, all seems well.
> However, when a call is made and originated into a ConfBridge with video, Asterisk enters an infinite loop processing video updates (or something) and crashes within 5-10 seconds. Sometimes if it takes longer to crash, you'll start seeing "Extremely long queue length queueing to..." messages before it actually crashes.
> Softphone used for testing is MicroSIP on both ends.
> Call is made between two PJSIP endpoints on the same system.
> Issue replicates 100% of the time, even with just two endpoints involved on an otherwise idle system. Infinite loop of media updates, followed by a crash.
> No issues when not using ConfBridge. No issues when failing to specify the h264 codec in Originate, which causes video call to fail and use audio only. No issues when using audio only, no video.
> It almost *seems* like somehow the conf bridge is not handling something right and is causing something to bounce around and go back and forth forever.
> Culprit seems to be hundreds/thousands of these bouncing around: Content-Type: application/media_control+xml
> Debug attached, providing an example of the infinite loop: https://paste.interlinked.us/xsr1mtgc6r.txt
> Additionally, here is a SIP debug from the perspective of the called line during all of this (different call but same thing): https://paste.interlinked.us/hky3ws7o3w.txt
--
This message was sent by Atlassian JIRA
(v6.2#6252)
More information about the asterisk-bugs
mailing list