10-26-2012 06:09 AM - edited 03-21-2019 09:54 AM
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?
11-08-2012 06:37 AM
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...
11-08-2012 10:29 AM
Bryan,
Will search inbox/spam for your email, apologies for missing it.
Regards,
Patrick
-----------
11-10-2012 01:23 PM
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...
11-12-2012 06:44 AM
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
11-12-2012 07:17 AM
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.
11-12-2012 09:21 AM
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
----------
11-13-2012 09:09 AM
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
11-13-2012 09:23 AM
11-13-2012 09:30 PM
thank you Alex, will be ok for now
Let me know if there is anything I can do to help you debug the issue
11-12-2012 08:31 AM
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
11-12-2012 02:42 PM
Installed 1.3.0 this morning and it crashed/locked up in 3 1/2 hours.
Syslog has been sent to Patrick.
Thanks,
Bryan
11-18-2012 04:11 PM
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