cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
30378
Views
29
Helpful
117
Replies

SPA112 goes catatonic, crashes, no dialtone

sgtpanties
Level 1
Level 1

Hi, I am a home user and ex-programmer. I'm using the Cisco SPA112 with the latest firmware 1.2.1 (004). Every two or three days the ATA          crashes.  Here are the symptoms:

     a) no dial tone

     b) line 1 LED is ON (as normal)

     c) ATA is PINGable

     d) IVR is unresponsive

     e) web setup URL does not respond, can not get to the setup page

     f) The reset button does not respond.

My only recourse now is the check if I have a dialtone every morning and, if not, pull and reinsert the power plug.   The cold boot of course results in the local log being lost.  Here is the log right after boot up.

Jan  1 00:00:04 SPA112 kern.warning [    0.000000] CPU: ARM926EJ-S [41069265] revision 5 (ARMv5TEJ), cr=00053177

Jan  1 00:00:04 SPA112 kern.warning [    0.000000] Machine: NXP PNX8181

Jan  1 00:00:04 SPA112 kern.warning [    0.000000] Memory policy: ECC disabled, Data cache writeback

Jan  1 00:00:04 SPA112 kern.warning [    0.000000] CPU0: D VIVT write-back cache

Jan  1 00:00:04 SPA112 kern.warning [    0.000000] CPU0: I cache: 32768 bytes, associativity 4, 32 byte lines, 256 sets

Jan  1 00:00:04 SPA112 kern.warning [    0.000000] CPU0: D cache: 32768 bytes, associativity 4, 32 byte lines, 256 sets

Jan  1 00:00:04 SPA112 kern.warning [17179569.184000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 7874

Jan  1 00:00:04 SPA112 kern.warning [17179569.184000] PID hash table entries: 128 (order: 7, 512 bytes)

Jan  1 00:00:04 SPA112 kern.warning [17179569.184000] Console: colour dummy device 80x30

Jan  1 00:00:04 SPA112 kern.warning [17179569.260000] Mount-cache hash table entries: 512

Jan  1 00:00:04 SPA112 kern.warning [17179569.264000] Board HW MODEL : 0x3

Jan  1 00:00:04 SPA112 kern.warning [17179569.336000] squashfs: LZMA suppport for slax.org by jro

Jan  1 00:00:04 SPA112 kern.warning [17179569.384000] [ip3912] : Bridge Mode...

Jan  1 00:00:04 SPA112 kern.err [17179569.408000] physmap-flash physmap-flash.0: map_probe failed

Jan  1 00:00:04 SPA112 kern.warning [17179569.416000] Using Full Image\'s RootFS

Jan  1 00:00:04 SPA112 kern.warning [17179569.420000] Using static partition definition

Jan  1 00:00:04 SPA112 kern.warning [17179569.424000] !!! do adler32 checksum !!!

Jan  1 00:00:04 SPA112 kern.warning [17179571.260000] File system image checksum OK

Jan  1 00:00:04 SPA112 kern.err [17179571.344000] ksz8873 0-005f: failed with status -1

Jan  1 00:00:04 SPA112 kern.warning [17179571.348000] ksz8873: probe of 0-005f failed with error -1

Jan  1 00:00:04 SPA112 kern.warning [17179571.352000] PNX8181 watchdog timer: timer margin 16 sec

Jan  1 00:00:04 SPA112 kern.warning [17179571.372000] GACT probability on

Jan  1 00:00:04 SPA112 kern.warning [17179571.376000] Mirror/redirect action on

Jan  1 00:00:04 SPA112 kern.warning [17179571.380000] u32 classifier

Jan  1 00:00:04 SPA112 kern.warning [17179571.384000]     Performance counters on

Jan  1 00:00:04 SPA112 kern.warning [17179571.388000]     input device check on

Jan  1 00:00:04 SPA112 kern.warning [17179571.392000]     Actions configured

Jan  1 00:00:04 SPA112 kern.warning [17179571.396000] Netfilter messages via NETLINK v0.30.

Jan  1 00:00:04 SPA112 kern.warning [17179571.400000] nf_conntrack version 0.5.0 (1024 buckets, 4096 max)

Jan  1 00:00:04 SPA112 kern.warning [17179571.412000] ipt_time loading

Jan  1 00:00:04 SPA112 kern.warning [17179571.464000] VFS: Mounted root (squashfs filesystem) readonly.

Jan  1 00:00:04 SPA112 kern.warning [17179576.144000] ***** LED_DRV init *****

Jan  1 00:00:04 SPA112 kern.warning [17179576.148000] ***** LED_DRV end *****

Jan  1 00:00:04 SPA112 kern.warning [17179576.176000] *** sys event driver initialized ***

Jan  1 00:00:04 SPA112 kern.err [17179579.184000] br0: Dropping NETIF_F_UFO since no NETIF_F_HW_CSUM feature.

Jan  1 00:00:04 SPA112 kern.warning [17179580.880000] Empty flash at 0x00069454 ends at 0x00069600

Jan  1 00:00:05 SPA112 daemon.err dnsmasq[136]: failed to load names from /etc/hosts: No such file or directory

Jan  1 00:00:23 SPA112 kern.err [17179600.844000] br0: Dropping NETIF_F_UFO since no NETIF_F_HW_CSUM feature.

Jan  1 00:00:27 SPA112 daemon.err system[1]: notify add wan1 interface(br0)

Jan  1 00:00:27 SPA112 daemon.err system[1]: start 0 vlan-id... ok

Jan  1 00:00:39 SPA112 daemon.err dnsmasq[136]: failed to load names from /etc/hosts: No such file or directory

Here are some things I have tried on my own, to no effect:

      a) Tried running with Provisioning/Firmware Upgrade/Upgrade set to false.

      b) Reloaded the firmware with IE.  Before, I did it with FireFox.

      c) Did a factory reset and re-entered the settings suggested for my ATA from Callcentric.

How should I proceed?

117 Replies 117

bryanosborne
Level 1
Level 1

I sent Patrick an email, but had no response from him.

Not using CallCentric, but may configure Port 2 for that to see what happens.

Did set the NAT Keep Alive Enable and NAT Mapping Enable to "Yes" based on CallCentric config page, and the unit will stay up for close to 24 hours - instead of the 30-90 minutes prior to the change.

Noticed another post mentioned Line 1 light on when locked up, but when I find my unit frozen, the Line 1 light is NOT on, there is no dialtone, and when the phone keys are pushed, I don't even hear any tones.

Hoping for a solution...

Bryan,

Will search inbox/spam for your email, apologies for missing it.

Regards,

Patrick

-----------

I have been running the 1.3.0 firmware for several days - it has been up continuously for 72 hours now which is a clear improvement (max 24 hrs with 1.2.1).  I have been testing it with fax'ing rather than voice so I cannot comment on voice quality but virtually all my faxes are going through.  Also, I have been monitoring the "SIP PING" reponse time which ranges from 40 to 70ms and does so in the same pattern throughout each day.  I have been also monitoring memory useage on the unit which starts at 8% free on reboot and finally settles at around 5% (sometimes as low as 3% briefly).  I question if this is enough free memory for the code to respond to every situation but so far it seems stable.  I will keep monitoring over this next week.  In general use, I probably will issue SIP notify to reboot maybe once a week just to ward off the evil spirits...

I installed the new 1.3.0 firmware last Thursday (4 days ago) and have experienced zero issues since.  The SPA112 has not lost registration nor has it crashed.  I have logging running just in case and will report issues if any arise.

Lester

I think I spoke too soon about my success with the lab version firmware.  Around midnight the SPA112 (1.3.0) went brain-dead.  It will ping but no web access and SNMP gets all fail. The 4 LEDs look normal but of course no dialtone.   I had been logging memory use and it started to increase about two hours before the crash.  This took about 4 1/2 days to die this time which is much better, but not stable enough for prime time use. 

Hi Dale,

Thanks for sharing your experience with the Community and me. I've filed this against

CDETS# CSCud16633 so we can properly address the issue.

For the rest of the Community, Dale is providing me his configuration and I've asked for debug level 3+Coma and a Wireshark trace over the next few days as the device runs and he's hopefully able to replicate the issue again.

Regards,

Patrick

----------

Dear Patrick, thank you for your email

If  it helps I can say that before upgrading to the latest fw the unit would freeze  in a day. With the new fw seem to last a couple of weeks, after which back to the same issue. The unit is new, got it a month ago.

As I use the unit with an italian voip provider and tested others as well in my view is not releated to the provider used

From the log nothing comes up.

As I have installed the spa to a customer in order to avoid compllaints I have to login remotely  every few days and reboot the box, very high-tech solution !

Last resort will be to give my spa2102 that works without any problem 24hours in tne last 5 years.After that I will trash the device and switch to another maker, a pitty as the old spa's worked for me without any problem

Personally I would be happy while Cisco finds a solution to add an automatic reboot option via a cronjob, easy and inexpensive to do.

If needed I could provide Cisco remote access to the spa if it helps

Regards

You can try my perl script:

https://supportforums.cisco.com/message/3775082#

3775082

thank you Alex, will be ok for now

Let me know if there is anything I can do to help you debug the issue

sgtpanties
Level 1
Level 1

OK, it has been about six days since I've upgraded.   The ATA lost registration only once, which probably is related to the problems Callcentric has been having.  Please note my phone use is extremely light, maybe one call per day.  I have experienced NO decrease in call quality.

Thanks,

tom

bryanosborne
Level 1
Level 1

Installed 1.3.0 this morning and it crashed/locked up in 3 1/2 hours.

Syslog has been sent to Patrick.

Thanks,

Bryan

glstewart23
Level 1
Level 1

I use an SPA112 with CallCentric. Now that the DDOS problems have subsided, my ATA continues to freeze pretty much every day. I have to power cycle first thing in the morning.

I have tried backing up the configuration, resetting the device, and restoring the configuration.

Log from recent boot shows some file system consistency errors.

Nov 18 15:42:49 SPA112 syslog.notice syslog-ng[120]: syslog-ng version 1.6.12 starting

Nov 18 15:42:49 SPA112 kern.notice [    0.000000] Linux version 2.6.26.5 (jlai2@ubuntu) (gcc version 4.1.2) #1 PREEMPT Thu Jul 5 20:35:49 PDT 2012

Nov 18 15:42:49 SPA112 kern.warning [    0.000000] CPU: ARM926EJ-S [41069265] revision 5 (ARMv5TEJ), cr=00053177

Nov 18 15:42:49 SPA112 kern.warning [    0.000000] Machine: NXP PNX8181

Nov 18 15:42:49 SPA112 kern.warning [    0.000000] Memory policy: ECC disabled, Data cache writeback

Nov 18 15:42:49 SPA112 kern.warning [    0.000000] CPU0: D VIVT write-back cache

Nov 18 15:42:49 SPA112 kern.warning [    0.000000] CPU0: I cache: 32768 bytes, associativity 4, 32 byte lines, 256 sets

Nov 18 15:42:49 SPA112 kern.warning [    0.000000] CPU0: D cache: 32768 bytes, associativity 4, 32 byte lines, 256 sets

Nov 18 15:42:49 SPA112 kern.warning [17179569.184000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 7874

Nov 18 15:42:49 SPA112 kern.notice [17179569.184000] Kernel command line: console=ttyS1,115200n8 rootfstype=squashfs noalign half_image=0 verify=y Hw_Model=SPA112 Router_Mode=0

Nov 18 15:42:49 SPA112 kern.info [17179569.184000] PNX8181: Configured 66 Interrupts

Nov 18 15:42:49 SPA112 kern.warning [17179569.184000] PID hash table entries: 128 (order: 7, 512 bytes)

Nov 18 15:42:49 SPA112 kern.warning [17179569.184000] Console: colour dummy device 80x30

Nov 18 15:42:49 SPA112 kern.info [17179569.184000] Dentry cache hash table entries: 4096 (order: 2, 16384 bytes)

Nov 18 15:42:49 SPA112 kern.info [17179569.184000] Inode-cache hash table entries: 2048 (order: 1, 8192 bytes)

Nov 18 15:42:49 SPA112 kern.info [17179569.188000] Memory: 31MB = 31MB total

Nov 18 15:42:49 SPA112 kern.notice [17179569.188000] Memory: 28296KB available (2804K code, 197K data, 120K init)

Nov 18 15:42:49 SPA112 kern.warning [17179569.260000] Mount-cache hash table entries: 512

Nov 18 15:42:49 SPA112 kern.info [17179569.260000] CPU: Testing write buffer coherency: ok

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] net_namespace: 484 bytes

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] NET: Registered protocol family 16

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] Board: found phy at 0x03, id 0x001cc815

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] Board: Vega_PNX8181_BaseStation low-cost version detected.

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] clock [intc] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] clock [sctu] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] clock [uart1] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] clock [uart2] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] clock [sdi] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] clock [ebi1] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] clock [ebi2] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.264000] clock [gpio] enabled

Nov 18 15:42:49 SPA112 kern.notice [17179569.264000] gpio_init: Registered PNX818 GPIO device

Nov 18 15:42:49 SPA112 kern.warning [17179569.264000] Board HW MODEL : 0x3

Nov 18 15:42:49 SPA112 kern.info [17179569.268000] clock [extint] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.268000] External Interrupt Controller registered

Nov 18 15:42:49 SPA112 kern.info [17179569.284000] NET: Registered protocol family 2

Nov 18 15:42:49 SPA112 kern.info [17179569.320000] IP route cache hash table entries: 1024 (order: 0, 4096 bytes)

Nov 18 15:42:49 SPA112 kern.info [17179569.320000] TCP established hash table entries: 1024 (order: 1, 8192 bytes)

Nov 18 15:42:49 SPA112 kern.info [17179569.320000] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)

Nov 18 15:42:49 SPA112 kern.info [17179569.320000] TCP: Hash tables configured (established 1024 bind 1024)

Nov 18 15:42:49 SPA112 kern.info [17179569.320000] TCP reno registered

Nov 18 15:42:49 SPA112 kern.info [17179569.332000] NET: Registered protocol family 1

Nov 18 15:42:49 SPA112 kern.info [17179569.332000] clock [dmau] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.332000] probe succeded

Nov 18 15:42:49 SPA112 kern.info [17179569.332000] clock [iic] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.336000] squashfs: version 3.4 (2008/08/26) Phillip Lougher

Nov 18 15:42:49 SPA112 kern.warning [17179569.336000] squashfs: LZMA suppport for slax.org by jro

Nov 18 15:42:49 SPA112 kern.info [17179569.336000] JFFS2 version 2.2. (NAND) © 2001-2006 Red Hat, Inc.

Nov 18 15:42:49 SPA112 kern.info [17179569.340000] msgmni has been set to 55

Nov 18 15:42:49 SPA112 kern.info [17179569.340000] io scheduler noop registered (default)

Nov 18 15:42:49 SPA112 kern.info [17179569.348000] HDLC line discipline: version $Revision: 1.1.1.1 $, maxframe=4096

Nov 18 15:42:49 SPA112 kern.info [17179569.348000] N_HDLC line discipline registered.

Nov 18 15:42:49 SPA112 kern.info [17179569.348000] Non-volatile memory driver v1.2

Nov 18 15:42:49 SPA112 kern.info [17179569.348000] Serial: 8250/16550 driver $Revision: 1.1.1.1 $ 2 ports, IRQ sharing disabled

Nov 18 15:42:49 SPA112 kern.info [17179569.348000] serial8250.0: ttyS0 at MMIO 0xc2004000 (irq = 24) is a 16550A

Nov 18 15:42:49 SPA112 kern.info [17179569.348000] serial8250.0: ttyS1 at MMIO 0xc2005000 (irq = 23) is a 16550A

Nov 18 15:42:49 SPA112 kern.info [17179569.352000] console [ttyS1] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.356000] DCC: JTAG1 Serial emulation driver driver $Revision: 1.1.1.1 $

Nov 18 15:42:49 SPA112 kern.info [17179569.360000] ttyJ0 at MMIO 0x12345678 (irq = 0) is a DCC

Nov 18 15:42:49 SPA112 kern.info [17179569.364000] brd: module loaded

Nov 18 15:42:49 SPA112 kern.info [17179569.368000] PPP generic driver version 2.4.2

Nov 18 15:42:49 SPA112 kern.info [17179569.372000] PPP MPPE Compression module registered

Nov 18 15:42:49 SPA112 kern.info [17179569.376000] NET: Registered protocol family 24

Nov 18 15:42:49 SPA112 kern.info [17179569.380000] PPPoL2TP kernel driver, V1.0

Nov 18 15:42:49 SPA112 kern.warning [17179569.384000] [ip3912] : Bridge Mode...

Nov 18 15:42:49 SPA112 kern.info [17179569.384000] clock [etn1] enabled

Nov 18 15:42:49 SPA112 kern.info [17179569.392000] ip3912_mii_bus: probed

Nov 18 15:42:49 SPA112 kern.info [17179569.396000] eth0: IP3912 at 0xc1600000 using 0:03 phy

Nov 18 15:42:49 SPA112 kern.info [17179569.400000] NFTL driver: nftlcore.c $Revision: 1.1.1.1 $, nftlmount.c $Revision: 1.1.1.1 $

Nov 18 15:42:49 SPA112 kern.notice [17179569.404000] physmap platform flash device: 02000000 at 80000000

Nov 18 15:42:49 SPA112 kern.err [17179569.408000] physmap-flash physmap-flash.0: map_probe failed

Nov 18 15:42:49 SPA112 kern.info [17179569.412000] NAND device: Manufacturer ID: 0xad, Chip ID: 0x75 (Hynix NAND 32MiB 3,3V 8-bit)

Nov 18 15:42:49 SPA112 kern.warning [17179569.416000] Using Full Image\'s RootFS

Nov 18 15:42:49 SPA112 kern.warning [17179569.420000] Using static partition definition

Nov 18 15:42:49 SPA112 kern.warn