I've been testing a new asterisk server, and wondering the circumstances
that can bring on the "iax2_read: I should never be called" message.
I've been dismissing it as a network hiccough (we have two t1s with bgp
and do see them switch over once in a while, several sip users are using
comcast, there's an asterisk box connected via comcast as well), but
perhaps it could be tied to that the tdm400 board is sharing an
interrupt (and I'm trying to figure out how to get the bios not to do this)
1) would excessive interrupt latency cause iax2_read to emit that message?
2) Or a network problem? do other people see that message periodically?
any other circumstances where we might see this sort of stuff?
Sep 20 11:30:37 NOTICE[1118833584]: chan_iax2.c:2442 iax2_read: I should
never be called!
Sep 20 11:30:38 NOTICE[1108265904]: chan_sip.c:7837 sip_poke_noanswer:
Peer 'gbrophone' is now UNREACHABLE!
Sep 20 11:30:39 NOTICE[1108265904]: chan_sip.c:7837 sip_poke_noanswer:
Peer 'gbrown' is now UNREACHABLE!
Sep 20 11:30:47 NOTICE[1118833584]: chan_iax2.c:2442 iax2_read: I should
never be called!
Sep 20 11:30:59 NOTICE[1108265904]: chan_sip.c:6577 handle_response:
Peer 'gbrophone' is now REACHABLE!
Sep 20 11:31:00 NOTICE[1108265904]: chan_sip.c:6577 handle_response:
Peer 'gbrown' is now REACHABLE!
Sep 20 11:31:38 NOTICE[1118833584]: chan_iax2.c:2442 iax2_read: I should
never be called!
Sep 20 11:32:09 NOTICE[1118833584]: chan_iax2.c:2442 iax2_read: I should
never be called!
numbers*CLI>
numbers*CLI> sip show peers
Name/username Host Dyn Nat ACL Mask Port
Status
gbrown/gbrown 24.5.133.130 D N 255.255.255.255 5060
OK (35 ms)
gbrophone/gbrop 24.5.133.130 D N 255.255.255.255 5060
OK (30 ms)
ep 20 11:44:25 NOTICE[1120934832]: chan_iax2.c:2442 iax2_read: I should
never be called!
numbers*CLI> sip show channels
Peer User/ANR Call ID Seq (Tx/Rx) Format
199.108.16.15 war 637f6906704 00102/00000 ULAW
24.5.133.130 gbrophone 1a3371c1a3d 00101/52360 ULAW
2 active SIP channel(s)
numbers*CLI> channel 6: open failed: connect failed: Connection refused
Sep 20 11:48:18 NOTICE[1108265904]: chan_sip.c:7837 sip_poke_noanswer:
Peer 'gbrown' is now UNREACHABLE!
Sep 20 11:48:18 NOTICE[1108265904]: chan_sip.c:7837 sip_poke_noanswer:
Peer 'gbrophone' is now UNREACHABLE!
numbers*CLI> channel 6: open failed: connect failed: Connection refused
Sep 20 11:48:28 NOTICE[1108265904]: chan_sip.c:6577 handle_response:
Peer 'gbrown' is now REACHABLE!
Sep 20 11:48:28 NOTICE[1108265904]: chan_sip.c:6577 handle_response:
Peer 'gbrophone' is now REACHABLE!
numbers*CLI> channel 6: open failed: connect failed: Connection refused
channel 6: open failed: connect failed: Connection refused
channel 6: open failed: connect failed: Connection refused
Sep 20 12:18:47 NOTICE[1094572976]: chan_iax2.c:6131 iax2_poke_noanswer:
Peer 'vpconnect-t02' is now UNREACHABLE!
Sep 20 12:18:47 NOTICE[1094572976]: chan_iax2.c:6131 iax2_poke_noanswer:
Peer 'vpconnect-t01' is now UNREACHABLE!
numbers*CLI>
Sep 20 12:18:57 NOTICE[1094572976]: chan_iax2.c:5614 socket_read: Peer
'vpconnect-t02' is now REACHABLE!
Sep 20 12:18:57 NOTICE[1094572976]: chan_iax2.c:5614 socket_read: Peer
'vpconnect-t01' is now REACHABLE!
numbers:~ # cat /proc/interrupts
CPU0
0: 161507534 XT-PIC timer
1: 5419 XT-PIC i8042
2: 0 XT-PIC cascade
5: 161339814 XT-PIC ohci_hcd, wctdm
8: 2 XT-PIC rtc
9: 0 XT-PIC acpi
10: 17647373 XT-PIC eth0, ehci_hcd
11: 1683817 XT-PIC ide2, ohci_hcd
12: 49626 XT-PIC i8042
14: 21465 XT-PIC ide0
NMI: 0
LOC: 0
ERR: 0
MIS: 0