[asterisk-bugs] [JIRA] (ASTERISK-27321) Asterisk Crashing with FRACK Errors and Serious Network Trouble
Steven Sedory (JIRA)
noreply at issues.asterisk.org
Sun Nov 12 19:28:33 CST 2017
[ https://issues.asterisk.org/jira/browse/ASTERISK-27321?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=239958#comment-239958 ]
Steven Sedory edited comment on ASTERISK-27321 at 11/12/17 7:26 PM:
--------------------------------------------------------------------
So we had some FRACKs yesterday. Do you just need to see the asterisk log from yesterday? If so, I've attached "full-20171112".
Here's part of the log near one of the FRACKs:
[2017-11-11 06:03:49] VERBOSE[7195] asterisk.c: Remote UNIX connection
[2017-11-11 06:03:49] VERBOSE[31005] asterisk.c: Remote UNIX connection disconnected
[2017-11-11 06:03:50] WARNING[8721] chan_sip.c: Unable to cancel schedule ID 0. This is probably a bug (chan_sip.c: do_dialog_unlink_sched_items, line 3266).
[2017-11-11 06:03:50] ERROR[5146] /builddir/build/BUILD/asterisk-13.17.2/include/asterisk/utils.h: Memory Allocation Failure in function ast_str_create at line 655 of /builddir/build/BUILD/asterisk-13.17.2/include/asterisk/strings.h
[2017-11-11 06:03:50] WARNING[5146] chan_sip.c: sip_xmit of 0x7f0428c3af80 (len 139655827686296) to 108.23.78.98:4279 returned -2: Cannot allocate memory
[2017-11-11 06:03:50] ERROR[5146] chan_sip.c: Serious Network Trouble; __sip_xmit returns error for pkt data
[2017-11-11 06:03:50] ERROR[5146] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x7f04286eac38 (0)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: Got 23 backtrace records
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #0: [0x607112] asterisk __ast_assert_failed() (0x60708a+88)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #1: [0x45e2c6] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #2: [0x45e958] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #3: [0x45edcc] asterisk __ao2_ref() (0x45ed9b+31)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #4: [0x7f03be9d6a65] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #5: [0x7f03be9d6eab] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #6: [0x7f03be9d8e9d] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #7: [0x7f03bea0685b] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #8: [0x7f03bea57ec8] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #9: [0x7f03bea14753] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #10: [0x7f03bea1777f] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #11: [0x7f03bea513e0] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #12: [0x7f03bea52c56] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #13: [0x7f03bea535fd] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #14: [0x7f03be9d2c62] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #15: [0x7f03be9d181c] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #16: [0x5f076f] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #17: [0x603d14] asterisk <unknown>()
[2017-11-11 06:03:51] ERROR[5146] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x7f04286eac38 (0)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: Got 22 backtrace records
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #0: [0x607112] asterisk __ast_assert_failed() (0x60708a+88)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #1: [0x45e2c6] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #2: [0x45e958] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #3: [0x45edcc] asterisk __ao2_ref() (0x45ed9b+31)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #4: [0x7f03be9d6ebc] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #5: [0x7f03be9d8e9d] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #6: [0x7f03bea0685b] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #7: [0x7f03bea57ec8] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #8: [0x7f03bea14753] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #9: [0x7f03bea1777f] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #10: [0x7f03bea513e0] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #11: [0x7f03bea52c56] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #12: [0x7f03bea535fd] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #13: [0x7f03be9d2c62] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #14: [0x7f03be9d181c] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #15: [0x5f076f] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #16: [0x603d14] asterisk <unknown>()
[2017-11-11 06:03:51] ERROR[8721] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x7f04286eac38 (0)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: Got 13 backtrace records
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #0: [0x607112] asterisk __ast_assert_failed() (0x60708a+88)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #1: [0x45e2c6] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #2: [0x45e958] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #3: [0x45edcc] asterisk __ao2_ref() (0x45ed9b+31)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #4: [0x7f03be9d6a25] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #5: [0x5c33a6] asterisk ast_sched_runq() (0x5c3267+13F)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #6: [0x7f03bea55405] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #7: [0x603d14] asterisk <unknown>()
[2017-11-11 06:03:51] ERROR[8721] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x7f04286eac38 (0)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: Got 13 backtrace records
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #0: [0x607112] asterisk __ast_assert_failed() (0x60708a+88)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #1: [0x45e2c6] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #2: [0x45e958] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #3: [0x45edcc] asterisk __ao2_ref() (0x45ed9b+31)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #4: [0x7f03be9d6a41] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #5: [0x5c33a6] asterisk ast_sched_runq() (0x5c3267+13F)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #6: [0x7f03bea55405] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #7: [0x603d14] asterisk <unknown>()
[2017-11-11 06:03:57] VERBOSE[7195] asterisk.c: Remote UNIX connection
[2017-11-11 06:03:57] VERBOSE[31025] asterisk.c: Remote UNIX connection disconnected
was (Author: stevensedory):
So we had some FRACKs yesterday. Do you just need to see the asterisk log from yesterday? If so, I've attached "full-20171112.
Here's part of the log near one of the FRACKs:
[2017-11-11 06:03:49] VERBOSE[7195] asterisk.c: Remote UNIX connection
[2017-11-11 06:03:49] VERBOSE[31005] asterisk.c: Remote UNIX connection disconnected
[2017-11-11 06:03:50] WARNING[8721] chan_sip.c: Unable to cancel schedule ID 0. This is probably a bug (chan_sip.c: do_dialog_unlink_sched_items, line 3266).
[2017-11-11 06:03:50] ERROR[5146] /builddir/build/BUILD/asterisk-13.17.2/include/asterisk/utils.h: Memory Allocation Failure in function ast_str_create at line 655 of /builddir/build/BUILD/asterisk-13.17.2/include/asterisk/strings.h
[2017-11-11 06:03:50] WARNING[5146] chan_sip.c: sip_xmit of 0x7f0428c3af80 (len 139655827686296) to 108.23.78.98:4279 returned -2: Cannot allocate memory
[2017-11-11 06:03:50] ERROR[5146] chan_sip.c: Serious Network Trouble; __sip_xmit returns error for pkt data
[2017-11-11 06:03:50] ERROR[5146] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x7f04286eac38 (0)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: Got 23 backtrace records
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #0: [0x607112] asterisk __ast_assert_failed() (0x60708a+88)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #1: [0x45e2c6] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #2: [0x45e958] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #3: [0x45edcc] asterisk __ao2_ref() (0x45ed9b+31)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #4: [0x7f03be9d6a65] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #5: [0x7f03be9d6eab] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #6: [0x7f03be9d8e9d] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #7: [0x7f03bea0685b] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #8: [0x7f03bea57ec8] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #9: [0x7f03bea14753] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #10: [0x7f03bea1777f] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #11: [0x7f03bea513e0] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #12: [0x7f03bea52c56] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #13: [0x7f03bea535fd] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #14: [0x7f03be9d2c62] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #15: [0x7f03be9d181c] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #16: [0x5f076f] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #17: [0x603d14] asterisk <unknown>()
[2017-11-11 06:03:51] ERROR[5146] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x7f04286eac38 (0)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: Got 22 backtrace records
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #0: [0x607112] asterisk __ast_assert_failed() (0x60708a+88)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #1: [0x45e2c6] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #2: [0x45e958] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #3: [0x45edcc] asterisk __ao2_ref() (0x45ed9b+31)
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #4: [0x7f03be9d6ebc] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #5: [0x7f03be9d8e9d] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #6: [0x7f03bea0685b] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #7: [0x7f03bea57ec8] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #8: [0x7f03bea14753] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #9: [0x7f03bea1777f] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #10: [0x7f03bea513e0] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #11: [0x7f03bea52c56] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #12: [0x7f03bea535fd] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #13: [0x7f03be9d2c62] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #14: [0x7f03be9d181c] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #15: [0x5f076f] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[5146] logger.c: #16: [0x603d14] asterisk <unknown>()
[2017-11-11 06:03:51] ERROR[8721] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x7f04286eac38 (0)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: Got 13 backtrace records
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #0: [0x607112] asterisk __ast_assert_failed() (0x60708a+88)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #1: [0x45e2c6] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #2: [0x45e958] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #3: [0x45edcc] asterisk __ao2_ref() (0x45ed9b+31)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #4: [0x7f03be9d6a25] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #5: [0x5c33a6] asterisk ast_sched_runq() (0x5c3267+13F)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #6: [0x7f03bea55405] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #7: [0x603d14] asterisk <unknown>()
[2017-11-11 06:03:51] ERROR[8721] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x7f04286eac38 (0)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: Got 13 backtrace records
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #0: [0x607112] asterisk __ast_assert_failed() (0x60708a+88)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #1: [0x45e2c6] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #2: [0x45e958] asterisk <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #3: [0x45edcc] asterisk __ao2_ref() (0x45ed9b+31)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #4: [0x7f03be9d6a41] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #5: [0x5c33a6] asterisk ast_sched_runq() (0x5c3267+13F)
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #6: [0x7f03bea55405] chan_sip.so <unknown>()
[2017-11-11 06:03:51] VERBOSE[8721] logger.c: #7: [0x603d14] asterisk <unknown>()
[2017-11-11 06:03:57] VERBOSE[7195] asterisk.c: Remote UNIX connection
[2017-11-11 06:03:57] VERBOSE[31025] asterisk.c: Remote UNIX connection disconnected
> Asterisk Crashing with FRACK Errors and Serious Network Trouble
> ---------------------------------------------------------------
>
> Key: ASTERISK-27321
> URL: https://issues.asterisk.org/jira/browse/ASTERISK-27321
> Project: Asterisk
> Issue Type: Bug
> Security Level: None
> Components: Channels/chan_sip/General
> Affects Versions: 13.17.0
> Environment: FreePBX 13.0.192.16 and Asterisk 13.17.0, proxmox 4.4 on Dell R720, local RAID volume. Using TCP and obscure port for SIP. UDP 5060 still open/enabled, but firewalled to only allow Anveo Direct servers.
> Reporter: Steven Sedory
> Assignee: Unassigned
> Severity: Critical
> Attachments: backtrace-2017-10-25.txt, backtrace.txt, backtrace-with-debuginfo.txt, best-backtrace.txt, full, full-20171112
>
>
> Running FreePBX 13.0.192.16 and Asterisk 13.17.0
> I have previously posted about this issue in the freepbx and astersk forums. Here are those links:
> https://community.asterisk.org/t/asterisk-freepbx-crashing-and-frack-errors/72159
> https://community.freepbx.org/t/consistent-asterisk-freepbx-crash-issue/43682/1
> Host: Dell R720 with 2x Xeon E5-2620 2.00GHz (6 Core) and 64GB RAM DDR3 ECC), local PERC storage.
> Hypervisor: Proxmox 4.4-1.
> Network: using onboard Quad NIC. Bridge “vmbr0” points to “bond0” as the bridge port, and bond0 has eth0 and eth1 in it in “active-backup” mode, each going to one of our two core switches. Using Cisco 3560G. Switch ports are in trunk mode, with native vlan set to our management vlan. VMs are tagged to our public facing vlan, for direct internet access.
> VMs are running FreePBX/Asterisk versions mentioned above. Each have 4GB RAM fixed with ballooning disabled, 4 cores (2 sockets, 2 cores; have tried with NUMA enabled and disabled) with type “Default (kvm64)”, NIC using E1000 model, vdisk is 300G presented as ide0 as a raw image on a local LVM-Thin volume.
> Endpoints: All endpoints are NAT’d. We use TCP for SIP with an obscure port (not 5060 or near that). RTP traffic on our VSP’s required port range is allowed as well. All other traffic is dropped per the FreePBX firewall.
> In summary, what is happening is that we get a bunch of errors like this:
> [2017-09-28 02:05:18] ERROR[7061] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x3e7c690 (0)
> [2017-09-28 02:05:24] ERROR[6934] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x3e7c690 (0)
> [2017-09-28 02:05:28] ERROR[7107] astobj2.c: FRACK!, Failed assertion bad magic number 0x0 for object 0x3e7c690 (0)
> and right before and after, we have most of our peers go unreachable. Sometime Asterisk will crash afterwards, sometimes not.
> The issue happens intermittently, but seems to happen more frequently on the VMs that have more peers/endpoints (100+). I don’t think we’ve had it happen on any VMs that had less than 100 peers/endpoints.
> We recently chopped a server that had about 130 endpoints into two of 110 and 20. More accurately, we moved 110 off server A to server B, leaving 20 on server B. Before that move, we were experiencing FRACK! errors every day (anywhere from 20-300, usually all within a 20 minute window or so). Once the 110 were moved to server B, server A has never again had FRACK! errors or asterisk crashes. Server B however is having them now, just much less often then when all 130 endpoints were on server A. My assumption for that is due to the slightly lower endpoint total on the VM.
> This morning was one of those instances. We had 193 errors, identical to the three I posted above (minus the ERROR[number] being different). AND, we had a crash afterwards. Here is the backtrace: http://pastebin.freepbx.org/view/8cccc15f2
> So I come to you, the asterisk community, for help. I first posted on the FreePBX forum, and was directed here.
> I understand this may point to a memory issue, but what is strange is that the Dell iDrac log doesn’t show any memory errors in it. Perhaps there are errors but iDrac just isn’t seeing them to report them. I’m hoping someone out there can parse through the backtrace and give me a clear answer to what the problem is. Thanks in advance.
--
This message was sent by Atlassian JIRA
(v6.2#6252)
More information about the asterisk-bugs
mailing list