[CRASH] 3.5 rtp_relay #3732
Description
Happens on high traffic, maybe because BYE or other methods received for dialog not in adequate state, then used by rtp_relay to wrongly do things?
debian 11, 16 virtual core with 16 actual dedicated hardware cores, KVM
latest DEB package for 3.5
dpkg -l|grep opensips ii opensips 3.5.7-1 amd64 very fast and configurable SIP server ii opensips-auth-modules:amd64 3.5.7-1 amd64 Authentication interfaces for OpenSIPS ii opensips-cli 0.3.1~20241129~6124cb1-1 all Interactive command-line tool for OpenSIPS 3.0+ ii opensips-compression-module:amd64 3.5.7-1 amd64 Headers and body compression module for OpenSIPS ii opensips-dialplan-module:amd64 3.5.7-1 amd64 Generic string translation module for OpenSIPS ii opensips-http-modules:amd64 3.5.7-1 amd64 HTTP transport layer and Management Interface for OpenSIPS ii opensips-json-module:amd64 3.5.7-1 amd64 Support for JSON handling in OpenSIPS script ii opensips-postgres-module:amd64 3.5.7-1 amd64 PostgreSQL database connectivity module for OpenSIPS ii opensips-presence-modules:amd64 3.5.7-1 amd64 SIMPLE presence modules for OpenSIPS ii opensips-redis-module:amd64 3.5.7-1 amd64 Interface module to interact with a Redis server ii opensips-regex-module:amd64 3.5.7-1 amd64 PCRE regexp modules for OpenSIPS ii opensips-restclient-module:amd64 3.5.7-1 amd64 REST client module for OpenSIPS ii opensips-tls-module:amd64 3.5.7-1 amd64 TLS transport module for OpenSIPS ii opensips-tls-wolfssl-module:amd64 3.5.7-1 amd64 TLS transport module for OpenSIPS ii opensips-tlsmgm-module:amd64 3.5.7-1 amd64 TLS management module for OpenSIPS ii opensips-wss-module:amd64 3.5.7-1 amd64 WebSocket Secure (WSS) transport module for OpenSIPS ii opensips-xmlrpc-module:amd64 3.5.7-1 amd64 XMLRPC support for OpenSIPS's Management Interface ii python3-opensips 0.1.5~20250211~4f67b9d-1 all A collection of Python packages for OpenSIPS. === crash log: Oct 3 10:11:20 proxy01 opensips[771756]: Oct 3 10:11:20 [771756] NOTICE:tm:utimer_routine: time spent: 0.091s now at 75%+ capacity, inuse_transactions: 9867 Oct 3 10:11:20 proxy01 opensips[771750]: Oct 3 10:11:20 [771750] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d01ed3030 [1154:416497501] with clid '[email protected]' and tags 'as30b353a3' '' Oct 3 10:11:28 proxy01 opensips[771742]: Oct 3 10:11:28 [771742] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d05f1f310 [3034:1274284617] with clid '[email protected]' and tags 'as3f2a0bf2' '' Oct 3 10:11:38 proxy01 opensips[771746]: Oct 3 10:11:38 [771746] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d03688248 [2134:1531798406] with clid '[email protected]' and tags 'as6e132d46' 'bc50ce2a903c85174e632a501a52428d' Oct 3 10:11:42 proxy01 opensips[771751]: Oct 3 10:11:42 [771751] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cff848350 [941:1756659358] with clid '[email protected]' and tags 'as40c01a5b' 'de6e0628a3ed83d35c2066424821c666' Oct 3 10:11:47 proxy01 opensips[771745]: Oct 3 10:11:47 [771745] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cf3ef18a8 [2426:1536227085] with clid '[email protected]' and tags 'as5d4cfd35' '8fe2e4d8b43769e8d8d43dbe32a558a3' Oct 3 10:11:53 proxy01 opensips[771750]: Oct 3 10:11:53 [771750] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d0f59bc98 [1705:2064733217] with clid '[email protected]' and tags 'as03783db1' '3a81e4d8adfcd92a3782a6160c958a3b' Oct 3 10:11:55 proxy01 opensips[771744]: Oct 3 10:11:55 [771744] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2ccf833848 [3727:89583723] with clid '[email protected]' and tags 'as372fdf41' '2f9e9c1cde198b4ee599e4e6953436af' Oct 3 10:12:00 proxy01 opensips[771753]: Oct 3 10:12:00 [771753] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d23841490 [841:710788453] with clid '[email protected]' and tags 'as297c56a5' '' Oct 3 10:12:12 proxy01 opensips[771743]: Oct 3 10:12:12 [771743] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cd1759658 [777:1049712488] with clid '[email protected]' and tags 'as4a732d4f' '' Oct 3 10:12:13 proxy01 opensips[771747]: Oct 3 10:12:13 [771747] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cc7863b58 [988:19582376] with clid '[email protected]' and tags 'as0fd8ffd7' '1ee4aHUrFyKNH' Oct 3 10:12:13 proxy01 opensips[771749]: Oct 3 10:12:13 [771749] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cf76ae470 [2638:1686087827] with clid '[email protected]' and tags 'as34eb543b' '' Oct 3 10:12:14 proxy01 opensips[771740]: Oct 3 10:12:14 [771740] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cf873bcf0 [1393:1878505030] with clid '[email protected]' and tags 'as6ba51bfe' '' Oct 3 10:12:15 proxy01 opensips[771758]: Oct 3 10:12:15 [771758] WARNING:core:handle_timer_job: timer job has a 30000 us delay in execution: trigger_time=47689430000 ijiffies=47689460000 ijiffies_extra=0 Oct 3 10:12:15 proxy01 opensips[771748]: Oct 3 10:12:15 [771748] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cf0819f00 [2406:702888267] with clid '[email protected]' and tags 'as44a93495' '' Oct 3 10:12:15 proxy01 opensips[771748]: Oct 3 10:12:15 [771748] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d1969fd78 [2819:312699250] with clid '[email protected]' and tags 'as4177d61a' '96f0dd3f272601851c4d3350fcac2679' Oct 3 10:12:15 proxy01 opensips[771752]: Oct 3 10:12:15 [771752] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cdd606238 [594:2103772829] with clid '[email protected]' and tags 'as638f70fc' 'acb9cd23fb28fa0937b47a8c27b9dd61' Oct 3 10:12:16 proxy01 opensips[771747]: Oct 3 10:12:16 [771747] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d0339df20 [3528:769492052] with clid '[email protected]' and tags 'as3d5056f4' '2641f7b6cc1d154fe434e7a3f5c27561' Oct 3 10:12:16 proxy01 opensips[771746]: Oct 3 10:12:16 [771746] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cde12fe28 [1926:2131679128] with clid '[email protected]' and tags 'as52d26673' '' Oct 3 10:12:17 proxy01 opensips[771746]: Oct 3 10:12:17 [771746] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cf6e3ffb8 [3616:230175709] with clid '[email protected]' and tags 'as674bbea6' '' Oct 3 10:12:17 proxy01 opensips[771744]: Oct 3 10:12:17 [771744] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cffd58d68 [366:1894485289] with clid '[email protected]' and tags 'as2b61aadd' '' Oct 3 10:12:17 proxy01 opensips[771740]: Oct 3 10:12:17 [771740] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d182a7ab8 [1147:998998700] with clid '[email protected]' and tags 'as7ac33b3a' '2e966817264d065f2f24082bc72947ea' Oct 3 10:12:17 proxy01 opensips[771744]: Oct 3 10:12:17 [771744] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cdd606238 [594:2103772829] with clid '[email protected]' and tags 'as638f70fc' 'acb9cd23fb28fa0937b47a8c27b9dd61' Oct 3 10:12:17 proxy01 opensips[771747]: Oct 3 10:12:17 [771747] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d28e91210 [1251:555614737] with clid '[email protected]' and tags 'as2b0ff84d' '14175b155904e11ac133ec92b348a02b' Oct 3 10:12:17 proxy01 opensips[771745]: Oct 3 10:12:17 [771745] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d12a8be10 [874:1043950875] with clid '[email protected]' and tags 'as244695ea' '' Oct 3 10:12:18 proxy01 opensips[771751]: Oct 3 10:12:18 [771751] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d12a8be10 [874:1043950875] with clid '[email protected]' and tags 'as244695ea' '' Oct 3 10:12:18 proxy01 opensips[771751]: Oct 3 10:12:18 [771751] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d1691b250 [1270:351184055] with clid '[email protected]' and tags 'as7ce5710f' '' Oct 3 10:12:19 proxy01 opensips[771751]: Oct 3 10:12:19 [771751] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d22881fa8 [3154:811013550] with clid '[email protected]' and tags 'as09160aad' '' Oct 3 10:12:19 proxy01 opensips[771744]: Oct 3 10:12:19 [771744] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2ceb598a58 [1820:1428319496] with clid '[email protected]' and tags 'as531853ee' '' Oct 3 10:12:20 proxy01 opensips[771743]: Oct 3 10:12:20 [771743] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cc459ffe0 [3189:1007601145] with clid '[email protected]' and tags 'as43ff140d' '' Oct 3 10:12:20 proxy01 opensips[771741]: Oct 3 10:12:20 [771741] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cd67350f8 [2185:1274392292] with clid '[email protected]' and tags 'as2d2a9053' '' Oct 3 10:12:20 proxy01 opensips[771749]: Oct 3 10:12:20 [771749] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d1f1f2c78 [156:53619280] with clid '[email protected]' and tags 'as4b253933' '' Oct 3 10:12:20 proxy01 opensips[771752]: Oct 3 10:12:20 [771752] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cdc5f3850 [1278:579831271] with clid '[email protected]' and tags 'as04db323a' '42caf30ddd1c0b41fbef16688debe68a' Oct 3 10:12:21 proxy01 opensips[771747]: Oct 3 10:12:21 [771747] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d0b11a3d8 [3264:635174871] with clid '[email protected]' and tags 'as4eaf3adf' '' Oct 3 10:12:21 proxy01 opensips[771743]: Oct 3 10:12:21 [771743] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d29e24eb8 [1191:414989060] with clid '[email protected]' and tags 'as08b766ed' '7594632f322697c369834c11c2a2e66b' Oct 3 10:12:22 proxy01 opensips[771742]: Oct 3 10:12:22 [771742] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cd9d23138 [998:924001744] with clid '[email protected]' and tags 'as64bdb683' '' Oct 3 10:12:22 proxy01 opensips[771748]: Oct 3 10:12:22 [771748] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d0716ce20 [848:274828842] with clid '[email protected]' and tags 'as11e93689' '' Oct 3 10:12:22 proxy01 opensips[771742]: Oct 3 10:12:22 [771742] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cd7389b98 [145:517036771] with clid '[email protected]' and tags 'as0643a031' 'd8054781fc1396a7d846f9c46db9dcfa' Oct 3 10:12:23 proxy01 opensips[771753]: Oct 3 10:12:23 [771753] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2ce02fdac0 [3600:268717851] with clid '[email protected]' and tags 'as60030e61' 'b7fbcd8dd123461c823d2e1fad9ff9b8' Oct 3 10:12:23 proxy01 opensips[771744]: Oct 3 10:12:23 [771744] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cf4df7eb8 [615:564693890] with clid '[email protected]' and tags 'as29bb33a8' '' Oct 3 10:12:23 proxy01 opensips[771748]: Oct 3 10:12:23 [771748] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d27db9d48 [1392:1302190982] with clid '[email protected]' and tags 'as44876900' '9f2886955c6cad735b08ab9cb7746bb9' Oct 3 10:12:23 proxy01 opensips[771750]: Oct 3 10:12:23 [771750] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cd9fa8bc8 [3460:116412292] with clid '[email protected]' and tags 'as313ba352' '' Oct 3 10:12:23 proxy01 opensips[771739]: Oct 3 10:12:23 [771739] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cd6028058 [2258:1855525013] with clid '[email protected]' and tags 'as0a1f2053' '' Oct 3 10:12:24 proxy01 opensips[771744]: Oct 3 10:12:24 [771744] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cff9915b0 [2189:2028528794] with clid '[email protected]' and tags 'as3e29625a' '' Oct 3 10:12:24 proxy01 opensips[771739]: Oct 3 10:12:24 [771739] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cc4be7008 [3485:687522259] with clid '[email protected]' and tags 'as26509b19' '' Oct 3 10:12:24 proxy01 opensips[771754]: Oct 3 10:12:24 [771754] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cd070d270 [2624:668402363] with clid '[email protected]' and tags 'as3cba1126' '' Oct 3 10:12:25 proxy01 opensips[771752]: Oct 3 10:12:25 [771752] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d2c8735b0 [2794:1001089526] with clid '[email protected]' and tags 'as3fc462bb' '' Oct 3 10:12:26 proxy01 opensips[771746]: Oct 3 10:12:26 [771746] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d194469e0 [3149:1345888579] with clid '[email protected]' and tags 'as0b3d4979' '' Oct 3 10:12:30 proxy01 opensips[771754]: Oct 3 10:12:30 [771754] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cc40f4958 [3105:893017348] with clid '[email protected]' and tags 'as27baf072' '' Oct 3 10:12:32 proxy01 opensips[771753]: Oct 3 10:12:32 [771753] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d0b6bd608 [1551:498072895] with clid '[email protected]' and tags 'as20fc025e' '' Oct 3 10:12:35 proxy01 opensips[771741]: Oct 3 10:12:35 [771741] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2ce6709fe0 [3623:1678547701] with clid '[email protected]' and tags 'as1bd2741f' '' Oct 3 10:12:47 proxy01 opensips[771752]: Oct 3 10:12:47 [771752] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cd9910bd0 [3075:1784327370] with clid '[email protected]' and tags 'as28616073' '' Oct 3 10:12:55 proxy01 opensips[771753]: Oct 3 10:12:55 [771753] ERROR:rtpengine:rtpe_function_call: proxy replied with error: Unknown call-id Oct 3 10:12:55 proxy01 opensips[771753]: Oct 3 10:12:55 [771753] ERROR:rtp_relay:rtp_relay_answer: could not engage answer! Oct 3 10:12:56 proxy01 opensips[771743]: Oct 3 10:12:56 [771743] ERROR:rtpengine:rtpe_function_call: proxy replied with error: Unknown call-id Oct 3 10:12:56 proxy01 opensips[771743]: Oct 3 10:12:56 [771743] ERROR:rtp_relay:rtp_relay_answer: could not engage answer! Oct 3 10:13:13 proxy01 opensips[771740]: Oct 3 10:13:13 [771740] ERROR:rtpengine:rtpe_function_call: proxy replied with error: Unknown call-id Oct 3 10:13:13 proxy01 opensips[771740]: Oct 3 10:13:13 [771740] ERROR:rtp_relay:rtp_relay_answer: could not engage answer! Oct 3 10:13:14 proxy01 opensips[771749]: Oct 3 10:13:14 [771749] ERROR:rtpengine:rtpe_function_call: proxy replied with error: Unknown call-id Oct 3 10:13:14 proxy01 opensips[771749]: Oct 3 10:13:14 [771749] ERROR:rtp_relay:rtp_relay_answer: could not engage answer! Oct 3 10:13:15 proxy01 opensips[771743]: Oct 3 10:13:15 [771743] ERROR:rtpengine:rtpe_function_call: proxy replied with error: Unknown call-id Oct 3 10:13:15 proxy01 opensips[771743]: Oct 3 10:13:15 [771743] ERROR:rtp_relay:rtp_relay_answer: could not engage answer! Oct 3 10:13:16 proxy01 opensips[771740]: Oct 3 10:13:16 [771740] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2ced7a2e78 [3330:1778898085] with clid '[email protected]' and tags 'as0cc18c61' '' Oct 3 10:13:17 proxy01 opensips[771749]: Oct 3 10:13:17 [771749] ERROR:rtpengine:rtpe_function_call: proxy replied with error: Unknown call-id Oct 3 10:13:17 proxy01 opensips[771749]: Oct 3 10:13:17 [771749] ERROR:rtp_relay:rtp_relay_answer: could not engage answer! Oct 3 10:13:18 proxy01 opensips[771752]: Oct 3 10:13:18 [771752] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cedd0b088 [1542:1481106051] with clid '[email protected]' and tags 'as742e275d' '' Oct 3 10:13:20 proxy01 opensips[771748]: Oct 3 10:13:20 [771748] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d05fd10b8 [3391:1545640359] with clid '[email protected]' and tags 'as5cb0ddd4' 'b440313badbc17eab9170938edd3004e' Oct 3 10:13:21 proxy01 opensips[771751]: Oct 3 10:13:21 [771751] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2ce7c21900 [3927:1840167238] with clid '[email protected]' and tags 'as44eb8625' '' Oct 3 10:13:21 proxy01 opensips[771754]: Oct 3 10:13:21 [771754] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d2d086f30 [3662:1575762920] with clid '[email protected]' and tags 'as59d9db49' '' Oct 3 10:13:21 proxy01 opensips[771741]: Oct 3 10:13:21 [771741] CRITICAL:core:sig_usr: segfault in process pid: 771741, id: 8 Oct 3 10:13:21 proxy01 kernel: [5529259.729785] opensips[771741]: segfault at 20000000f ip 00007f2c9180b761 sp 00007ffde08e26c0 error 4 in rtp_relay.so[7f2c91802000+15000] Oct 3 10:13:21 proxy01 kernel: [5529259.732429] Code: 01 00 00 00 75 02 86 03 85 c0 74 0d 85 ed 7f e9 e8 b4 6a ff ff eb e5 66 90 49 8b 9c 24 80 00 00 00 48 85 db 0f 84 e9 01 00 00 <8b> 43 04 a8 02 0f 85 ac 01 00 00 4d 85 f6 0f 84 d5 01 00 00 31 d2 Oct 3 10:13:22 proxy01 opensips[771745]: Oct 3 10:13:22 [771745] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2ce2f2b650 [4049:34873804] with clid '[email protected]' and tags 'as63daba83' 'ef224ac15494f5ebdaaa6e075c4447b7' Oct 3 10:13:23 proxy01 opensips[771746]: Oct 3 10:13:23 [771746] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2ce2f2b650 [4049:34873804] with clid '[email protected]' and tags 'as63daba83' 'ef224ac15494f5ebdaaa6e075c4447b7' Oct 3 10:13:23 proxy01 opensips[771753]: Oct 3 10:13:23 [771753] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cffeb6070 [1651:71203292] with clid '[email protected]' and tags 'as1e23eb1a' '' Oct 3 10:13:24 proxy01 opensips[771752]: Oct 3 10:13:24 [771752] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cfb0b1300 [2264:1426523103] with clid '[email protected]' and tags 'as0bd0b65a' '' Oct 3 10:13:24 proxy01 opensips[771751]: Oct 3 10:13:24 [771751] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cd4ca1200 [998:924001753] with clid '[email protected]' and tags 'as69d4fbd8' 'ff88fcfb34d503dc5a90643c25ee6bee' Oct 3 10:13:26 proxy01 opensips[771754]: Oct 3 10:13:26 [771754] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d1c459730 [2323:89918347] with clid '[email protected]' and tags 'as44f5c058' '' Oct 3 10:13:27 proxy01 opensips[771748]: Oct 3 10:13:27 [771748] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d2b941ff8 [617:2064231742] with clid '[email protected]' and tags 'as20d6ed95' '' Oct 3 10:13:28 proxy01 opensips[771753]: Oct 3 10:13:28 [771753] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d247829c8 [3493:2131274961] with clid '[email protected]' and tags 'as58663592' '' Oct 3 10:13:28 proxy01 opensips[771742]: Oct 3 10:13:28 [771742] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2ce5aa7960 [3610:459256394] with clid '[email protected]' and tags 'as3221062e' '' Oct 3 10:13:28 proxy01 opensips[771749]: Oct 3 10:13:28 [771749] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2ca0889a78 [1857:1364100462] with clid '[email protected]' and tags 'as165d522c' 'b96e1906f8f8de6bda7682b7e7c97760' Oct 3 10:13:29 proxy01 opensips[771754]: Oct 3 10:13:29 [771754] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2cf4b87138 [780:819994929] with clid '[email protected]' and tags 'as1e448adb' '9602c96accb20ca88154b23965277369' Oct 3 10:13:29 proxy01 opensips[771742]: Oct 3 10:13:29 [771742] WARNING:dialog:log_next_state_dlg: bogus event 7 in state 2 for dlg 0x7f2d0b422f20 [1858:1095213894] with clid '[email protected]' and tags 'as1b4a9636' '' Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:handle_sigs: child process 771741 exited by a signal 11 Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:handle_sigs: core was generated Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:handle_sigs: terminating due to SIGCHLD Oct 3 10:13:29 proxy01 opensips[771737]: Oct 3 10:13:29 [771737] INFO:core:sig_usr: signal 15 received Oct 3 10:13:29 proxy01 opensips[771738]: Oct 3 10:13:29 [771738] INFO:core:sig_usr: signal 15 received Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:shutdown_opensips: process 1(771734) [MI FIFO] terminated, still waiting for 32 more Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:shutdown_opensips: process 2(771735) [MI Datagram] terminated, still waiting for 31 more Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:shutdown_opensips: process 3(771736) [HTTPD 127.0.0.1:8888] terminated, still waiting for 30 more Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:shutdown_opensips: process 4(771737) [time_keeper] terminated, still waiting for 29 more Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:shutdown_opensips: process 5(771738) [timer] terminated, still waiting for 28 more Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:shutdown_opensips: process 34(771767) [TCP main] terminated, still waiting for 27 more Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:shutdown_opensips: process 6(771739) [SIP receiver udp:85.10.250.251:5060] terminated, still waiting for 26 more Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:shutdown_opensips: process 16(771749) [SIP receiver udp:85.10.250.251:5060] terminated, still waiting for 25 more Oct 3 10:13:29 proxy01 opensips[771733]: Oct 3 10:13:29 [771733] INFO:core:shutdown_opensips: process 21(771754) [SIP receiver udp:85.10.250.251:5060] terminated, still waiting for 24 more === Reading symbols from /usr/sbin/opensips... (No debugging symbols found in /usr/sbin/opensips) warning: Can't open file /dev/zero (deleted) during file-backed mapping note processing [New LWP 771741] [Thread debugging using libthread_db enabled] Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1". Core was generated by `/usr/sbin/opensips -P /run/opensips/opensips.pid -f /etc/opensips/opensips.cfg'. Program terminated with signal SIGSEGV, Segmentation fault. #0 0x00007f2c9180b761 in ?? () from /usr/lib/x86_64-linux-gnu/opensips/modules/rtp_relay.so (gdb) bt #0 0x00007f2c9180b761 in ?? () from /usr/lib/x86_64-linux-gnu/opensips/modules/rtp_relay.so #1 0x00007f2c91ad0a8a in run_dlg_callbacks () from /usr/lib/x86_64-linux-gnu/opensips/modules/dialog.so #2 0x00007f2c91aece30 in dlg_onroute () from /usr/lib/x86_64-linux-gnu/opensips/modules/dialog.so #3 0x00007f2c91acb8f4 in ?? () from /usr/lib/x86_64-linux-gnu/opensips/modules/dialog.so #4 0x00007f2c91a94671 in w_topology_hiding_match () from /usr/lib/x86_64-linux-gnu/opensips/modules/topology_hiding.so #5 0x000055ec3d9f8f15 in do_action () #6 0x000055ec3d9faaf0 in run_action_list () #7 0x000055ec3da70a54 in eval_expr () #8 0x000055ec3da70609 in eval_expr () #9 0x000055ec3da70529 in eval_expr () #10 0x000055ec3d9f803f in do_action () #11 0x000055ec3d9faaf0 in run_action_list () #12 0x000055ec3d9f9a31 in do_action () #13 0x000055ec3d9faaf0 in run_action_list () #14 0x000055ec3d9fab87 in ?? () #15 0x000055ec3d9f9bd9 in do_action () #16 0x000055ec3d9faaf0 in run_action_list () #17 0x000055ec3d9fb003 in run_top_route () #18 0x000055ec3da63cb6 in receive_msg () #19 0x000055ec3dba07b8 in ?? () #20 0x000055ec3db8ce95 in ?? () #21 0x000055ec3db8e006 in ?? () #22 0x000055ec3db93f1d in udp_start_processes () #23 0x000055ec3d9f545c in main () ============= kind of load (taken some minutes later): Oct 3 10:38:04 proxy01 opensips[994953]: Oct 3 10:38:04 [994953] WARNING:core:handle_timer_job: utimer job has a 20000 us delay in execution: trigger_time=192780000 ijiffies=192800000 ijiffies_extra=0 Oct 3 10:38:04 proxy01 opensips[994951]: Oct 3 10:38:04 [994951] WARNING:core:handle_timer_job: utimer job has a 20000 us delay in execution: trigger_time=192780000 ijiffies=192800000 ijiffies_extra=0 Oct 3 10:38:04 proxy01 opensips[994942]: Oct 3 10:38:04 [994942] WARNING:core:handle_timer_job: utimer job has a 20000 us delay in execution: trigger_time=192780000 ijiffies=192800000 ijiffies_extra=0 Oct 3 10:38:04 proxy01 opensips[994952]: Oct 3 10:38:04 [994952] WARNING:core:handle_timer_job: utimer job has a 30000 us delay in execution: trigger_time=192780000 ijiffies=192810000 ijiffies_extra=0 Oct 3 10:38:04 proxy01 opensips[994944]: Oct 3 10:38:04 [994944] WARNING:core:handle_timer_job: utimer job has a 30000 us delay in execution: trigger_time=192780000 ijiffies=192810000 ijiffies_extra=0 Oct 3 10:38:04 proxy01 opensips[994948]: Oct 3 10:38:04 [994948] WARNING:core:handle_timer_job: utimer job has a 40000 us delay in execution: trigger_time=192780000 ijiffies=192820000 ijiffies_extra=0 Oct 3 10:38:04 proxy01 opensips[994958]: Oct 3 10:38:04 [994958] WARNING:core:handle_timer_job: utimer job has a 40000 us delay in execution: trigger_time=192780000 ijiffies=192820000 ijiffies_extra=0 Oct 3 10:38:04 proxy01 opensips[994945]: Oct 3 10:38:04 [994945] NOTICE:tm:utimer_routine: time spent: 0.080s now at 75%+ capacity, inuse_transactions: 13816 Oct 3 10:38:04 proxy01 opensips[994939]: Oct 3 10:38:04 [994939] NOTICE:tm:utimer_routine: time spent: 0.098s now at 75%+ capacity, inuse_transactions: 13754 Oct 3 10:38:04 proxy01 opensips[994938]: Oct 3 10:38:04 [994938] WARNING:core:utimer_ticker: utimer task already scheduled 100 ms ago (now 192880 ms), delaying execution Oct 3 10:38:04 proxy01 opensips[994938]: Oct 3 10:38:04 [994938] WARNING:core:utimer_ticker: utimer task already scheduled 100 ms ago (now 192880 ms), delaying execution Oct 3 10:38:04 proxy01 opensips[994938]: Oct 3 10:38:04 [994938] WARNING:core:utimer_ticker: utimer task already scheduled 100 ms ago (now 192880 ms), delaying execution Oct 3 10:38:04 proxy01 opensips[994938]: Oct 3 10:38:04 [994938] WARNING:core:utimer_ticker: utimer task already scheduled 100 ms ago (now 192880 ms), delaying execution Oct 3 10:38:04 proxy01 opensips[994938]: Oct 3 10:38:04 [994938] WARNING:core:utimer_ticker: utimer task already scheduled 100 ms ago (now 192880 ms), delaying execution Oct 3 10:38:04 proxy01 opensips[994957]: Oct 3 10:38:04 [994957] NOTICE:tm:utimer_routine: time spent: 0.100s now at 75%+ capacity, inuse_transactions: 13740 Oct 3 10:38:04 proxy01 opensips[994942]: Oct 3 10:38:04 [994942] NOTICE:tm:utimer_routine: time spent: 0.082s now at 75%+ capacity, inuse_transactions: 13725 Oct 3 10:38:04 proxy01 opensips[994951]: Oct 3 10:38:04 [994951] NOTICE:tm:utimer_routine: time spent: 0.083s now at 75%+ capacity, inuse_transactions: 13725 Oct 3 10:38:04 proxy01 opensips[994944]: Oct 3 10:38:04 [994944] NOTICE:tm:utimer_routine: time spent: 0.078s now at 75%+ capacity, inuse_transactions: 13699 Oct 3 10:38:04 proxy01 opensips[994953]: Oct 3 10:38:04 [994953] NOTICE:tm:utimer_routine: time spent: 0.098s now at 75%+ capacity, inuse_transactions: 13685 Oct 3 10:38:15 proxy01 opensips[994956]: Oct 3 10:38:15 [994956] NOTICE:tm:utimer_routine: time spent: 0.076s now at 75%+ capacity, inuse_transactions: 15353 Oct 3 10:38:15 proxy01 opensips[994955]: Oct 3 10:38:15 [994955] NOTICE:tm:utimer_routine: time spent: 0.076s now at 75%+ capacity, inuse_transactions: 15349 Oct 3 10:38:15 proxy01 opensips[994966]: Oct 3 10:38:15 [994966] NOTICE:tm:utimer_routine: time spent: 0.076s now at 75%+ capacity, inuse_transactions: 15346 Oct 3 10:38:15 proxy01 opensips[994958]: Oct 3 10:38:15 [994958] NOTICE:tm:utimer_routine: time spent: 0.080s now at 75%+ capacity, inuse_transactions: 15338
OpenSIPS version you are running
Crash Core Dump
Describe the traffic that generated the bug
To Reproduce
Relevant System Logs
OS/environment information
- Operating System:
- OpenSIPS installation:
- other relevant information:
Additional context