cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
749
Views
0
Helpful
3
Replies

SPA8000 firmware upgrade failure

I have a number of SPA8000 devices deployed in a wide geographic area. These devices are provisioned using an XML configuration file located on an HTTP server using the profile_rule provisioning system. The devices are running firmware version 5.1.12. Last week we rolled out version 6.1.12 to these devices using the upgrade_rule to specify a new firmware image located on an HTTP server. After the upgrade, some of the line modules in the SPA8000s started to constantly reboot. The first module was stable, but lines 3-8 would reboot constantly. 

For the time being, we've rolled back to firmware version 5.1.12. The devices required a reboot after the firmware downgrade to get the modules serving lines 3-8 to register.

Has anyone else experienced this problem?

3 Replies 3

Dan Miley
Level 3
Level 3

Was your configuration file created for 5.1.12?  if so the format may be wrong for the 6.1.12.  You can download the SPC tool for the spa8000 and create a new factory default config file for 6.1.12, then push that out to your 8000s with your provisioning.

 

I'm not sure if it would work, but a conditional in your profile rule might allow 2 config files for each device, one for each version.  Please take this as a crude example and test in your lab before deploying.

<profile_rule>.($SWVER eq 5.1.12) ? http://server/5.1.12/$ma |  http://server/6.1.12/$ma

                          ^check fw ver        if equal   ^do this         otherwise    ^ do this

Details for provisioning rules and spc tool syntax are here  https://supportforums.cisco.com/document/37081/cisco-ip-telephony-devices-provisioning-guide

spa profile compiler for the spa8000 can be downloaded here.  http://software.cisco.com/portal/pub/download/portal/select.html?&mdfid=282414110&softwareid=282562500

 

do you have logs from the one with the issue?

 

Hope this helps,

 

Dan

 

Dan, thank you for your response.

 

The config file was indeed created for 5.1.12. I see that the SPC for 6.1.12 creates a configuration file with a number of new options to support trunking features, etc. Therefore, I've updated the configuration file to the latest version. 

With the new version in place, I'm seeing the same behavior. I do have a syslog and debug server configured, and it appears that the device is currently constantly rebooting due to a difference in the configuration each time the device comes up. Is there a way to figure out which configuration setting is causing the reboot? Here's the debug info through a few reboot cycles:

 

2014-10-15 16:16:53 Local0.Info 192.168.1.130 M0: System started: ip@192.168.1.130, reboot reason:H0
2014-10-15 16:16:53 Local0.Info 192.168.1.130 M0: System started: ip@192.168.1.130, reboot reason:H0
2014-10-15 16:16:53 Local1.Debug 192.168.1.130 M0: subnet mask: 255.255.255.0
2014-10-15 16:16:53 Local1.Debug 192.168.1.130 M0: gateway ip: 192.168.1.1
2014-10-15 16:16:53 Local1.Debug 192.168.1.130 M0: dns servers(1):
2014-10-15 16:16:53 Local1.Debug 192.168.1.130 M0: 192.168.1.1 
2014-10-15 16:16:53 Local1.Debug 192.168.1.130 M0:  
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: IDBG: st-0
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: fs:029101:029101:131072
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: fls:af:1:0:0
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: fbr:1:3000:3000:1e07c:0020:0021:6.1.12
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: fhs:01:0:0002:upg:app:1:5.1.9(a)
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: fhs:02:0:0003:upg:app:2:5.1.9(a)
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: fhs:03:0:0004:upg:app:0:5.1.10
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: fhs:04:0:0005:upg:app:1:5.1.10
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: fhs:05:0:0006:upg:app:2:5.1.10
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: fhs:06:0:0007:upg:app:0:5.1.12
2014-10-15 16:16:57 Local3.Debug 192.168.1.130 M0: fhs:07:0:0008:upg:app:1:5.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fhs:08:0:0009:upg:app:2:5.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fhs:09:0:000a:upg:app:0:6.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fhs:0a:0:000b:upg:app:1:6.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fhs:0b:0:000c:upg:app:2:6.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fhs:0c:0:000d:upg:app:0:5.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fhs:0d:0:000e:upg:app:1:5.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fhs:0e:0:000f:upg:app:2:5.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fhs:0f:0:0010:upg:app:0:6.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fhs:10:0:0011:upg:app:1:6.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fhs:11:0:0012:upg:app:2:6.1.12
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:16:58 Local3.Debug 192.168.1.130 M0: fu:1:e08e, 0003 0001
2014-10-15 16:17:00 Local0.Info 192.168.1.130 M1: System started: ip@192.168.2.2, reboot reason:H0
2014-10-15 16:17:00 Local0.Info 192.168.1.130 M1: System started: ip@192.168.2.2, reboot reason:H0
2014-10-15 16:17:00 Local1.Debug 192.168.1.130 M1: subnet mask: 255.255.255.0
2014-10-15 16:17:00 Local1.Debug 192.168.1.130 M1: gateway ip: 192.168.2.1
2014-10-15 16:17:00 Local1.Debug 192.168.1.130 M1: dns servers(1):
2014-10-15 16:17:00 Local1.Debug 192.168.1.130 M1: 192.168.1.1 
2014-10-15 16:17:00 Local1.Debug 192.168.1.130 M1:  
2014-10-15 16:17:01 Local3.Debug 192.168.1.130 M1: mslink server task up (1)
2014-10-15 16:17:05 Local3.Debug 192.168.1.130 M1: mslink client task (1)  gw=192.168.2.1  ip=192.168.2.2
2014-10-15 16:17:06 Local3.Debug 192.168.1.130 M0: MSLINK module 1 is at 192.168.2.2
2014-10-15 16:17:06 Local3.Debug 192.168.1.130 M0: MSLINK code module 1: 0(32) sn:0
2014-10-15 16:17:07 Local3.Debug 192.168.1.130 M1: IDBG: st-0
2014-10-15 16:17:07 Local3.Debug 192.168.1.130 M1: fs:008665:008665:131072
2014-10-15 16:17:07 Local3.Debug 192.168.1.130 M1: fls:af:1:0:0
2014-10-15 16:17:07 Local3.Debug 192.168.1.130 M1: fbr:0:3000:3000:05bf7:0008:0007:6.1.12
2014-10-15 16:17:07 Local3.Debug 192.168.1.130 M1: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:17:07 Local3.Debug 192.168.1.130 M1: fu:0:5c09, 0003 0001
2014-10-15 16:17:10 Local0.Info 192.168.1.130 M2: System started: ip@192.168.2.3, reboot reason:H0
2014-10-15 16:17:10 Local0.Info 192.168.1.130 M2: System started: ip@192.168.2.3, reboot reason:H0
2014-10-15 16:17:10 Local1.Debug 192.168.1.130 M2: subnet mask: 255.255.255.0
2014-10-15 16:17:10 Local1.Debug 192.168.1.130 M2: gateway ip: 192.168.2.1
2014-10-15 16:17:10 Local1.Debug 192.168.1.130 M2: dns servers(1):
2014-10-15 16:17:10 Local1.Debug 192.168.1.130 M2: 192.168.1.1 
2014-10-15 16:17:10 Local1.Debug 192.168.1.130 M2:  
2014-10-15 16:17:11 Local3.Debug 192.168.1.130 M2: mslink server task up (2)
2014-10-15 16:17:15 Local3.Debug 192.168.1.130 M2: mslink client task (2)  gw=192.168.2.1  ip=192.168.2.3
2014-10-15 16:17:16 Local3.Debug 192.168.1.130 M0: MSLINK module 2 is at 192.168.2.3
2014-10-15 16:17:16 Local3.Debug 192.168.1.130 M0: MSLINK code module 2: 0(32) sn:0
2014-10-15 16:17:17 Local0.Info 192.168.1.130 M3: System started: ip@192.168.2.4, reboot reason:H0
2014-10-15 16:17:17 Local0.Info 192.168.1.130 M3: System started: ip@192.168.2.4, reboot reason:H0
2014-10-15 16:17:17 Local1.Debug 192.168.1.130 M3: subnet mask: 255.255.255.0
2014-10-15 16:17:17 Local1.Debug 192.168.1.130 M3: gateway ip: 192.168.2.1
2014-10-15 16:17:17 Local1.Debug 192.168.1.130 M3: dns servers(1):
2014-10-15 16:17:17 Local1.Debug 192.168.1.130 M3: 192.168.1.1 
2014-10-15 16:17:17 Local1.Debug 192.168.1.130 M3:  
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M3: fs:008710:008710:131072
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M3: fls:af:1:0:0
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M3: fbr:0:3000:3000:05f70:0008:0007:6.1.12
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M3: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M2: IDBG: st-0
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M2: fs:008710:008710:131072
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M2: fls:af:1:0:0
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M2: fbr:0:3000:3000:0d592:0008:0007:6.1.12
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M2: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M3: fu:0:5f82, 0003 0001
2014-10-15 16:17:17 Local3.Debug 192.168.1.130 M2: fu:0:d5a4, 0003 0001
2014-10-15 16:17:18 Local3.Debug 192.168.1.130 M2: fu:0:d5b3, 1a08 0001
2014-10-15 16:17:18 Local3.Debug 192.168.1.130 M3: mslink server task up (3)
2014-10-15 16:17:23 Local3.Debug 192.168.1.130 M3: mslink client task (3)  gw=192.168.2.1  ip=192.168.2.4
2014-10-15 16:17:24 Local3.Debug 192.168.1.130 M0: mslink  P:8305  V:35929
2014-10-15 16:17:24 Local3.Debug 192.168.1.130 M0: MSLINK module 3 is at 192.168.2.4
2014-10-15 16:17:24 Local3.Debug 192.168.1.130 M0: MSLINK code module 3: 0(32) sn:0
2014-10-15 16:17:25 Local3.Debug 192.168.1.130 M3: fu:0:5f91, 1a08 0001
2014-10-15 16:17:25 Local3.Debug 192.168.1.130 M0: mslink  P:1224  V:6562
2014-10-15 16:17:27 Local3.Debug 192.168.1.130 M0: mslink  P:1497  V:8526
2014-10-15 16:17:29 Local3.Debug 192.168.1.130 M0: mslink  P:551  V:2371
2014-10-15 16:17:31 Local3.Debug 192.168.1.130 M3: system request reboot
2014-10-15 16:17:31 Local3.Debug 192.168.1.130 M2: system request reboot
2014-10-15 16:17:31 Local3.Debug 192.168.1.130 M1: CC_init
2014-10-15 16:17:31 Local0.Info 192.168.1.130 M1: SLIC is si3215
2014-10-15 16:17:31 Local0.Info 192.168.1.130 M1: SLIC is si3215
2014-10-15 16:17:31 Local3.Debug 192.168.1.130 M2: CC_init
2014-10-15 16:17:31 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:17:31 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:17:31 Local3.Debug 192.168.1.130 M2: fu:0:d5c8, 03e4 05b0 0001
2014-10-15 16:17:31 Local3.Debug 192.168.1.130 M1: IDBG[0]:4
2014-10-15 16:17:31 Local3.Debug 192.168.1.130 M1: IDBG[1]:5
2014-10-15 16:17:32 Local3.Debug 192.168.1.130 M0: CC_init
2014-10-15 16:17:32 Local3.Debug 192.168.1.130 M2: IDBG[0]:6
2014-10-15 16:17:32 Local3.Debug 192.168.1.130 M2: IDBG[1]:6
2014-10-15 16:17:32 Local0.Info 192.168.1.130 M0: SLIC is si3215
2014-10-15 16:17:32 Local0.Info 192.168.1.130 M0: SLIC is si3215
2014-10-15 16:17:32 Local3.Debug 192.168.1.130 M0: fu:1:e0a3, 03e4 05b0 0001
2014-10-15 16:17:33 Local0.Info 192.168.1.130 M1: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:17:33 Local0.Info 192.168.1.130 M1: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:17:33 Local0.Info 192.168.1.130 M1: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:17:33 Local0.Info 192.168.1.130 M1: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:17:33 Local3.Debug 192.168.1.130 M1: [0]RegOK. NextReg in 594 (1)
2014-10-15 16:17:33 Local3.Debug 192.168.1.130 M1: [1]RegOK. NextReg in 594 (1)
2014-10-15 16:17:33 Local3.Debug 192.168.1.130 M0: IDBG[0]:6
2014-10-15 16:17:33 Local3.Debug 192.168.1.130 M0: IDBG[1]:4
2014-10-15 16:17:33 Local2.Debug 192.168.1.130 M1: [0]SubOK
2014-10-15 16:17:33 Local2.Debug 192.168.1.130 M1: NextSub in 2147483646 (1)
2014-10-15 16:17:33 Local2.Debug 192.168.1.130 M1: [1]SubOK
2014-10-15 16:17:33 Local2.Debug 192.168.1.130 M1: NextSub in 2147483646 (1)
2014-10-15 16:17:33 Local3.Debug 192.168.1.130 M3: CC_init
2014-10-15 16:17:33 Local0.Info 192.168.1.130 M3: SLIC is si3215
2014-10-15 16:17:33 Local0.Info 192.168.1.130 M3: SLIC is si3215
2014-10-15 16:17:33 Local3.Debug 192.168.1.130 M3: fu:0:5fa6, 03e4 05b0 0001
2014-10-15 16:17:34 Local0.Info 192.168.1.130 M0: ++++ sip skt[3]= INVALID
2014-10-15 16:17:34 Local0.Info 192.168.1.130 M0: ++++ sip skt[3]= INVALID
2014-10-15 16:17:34 Local0.Info 192.168.1.130 M0: ++++ sip skt[4]= INVALID
2014-10-15 16:17:34 Local0.Info 192.168.1.130 M0: ++++ sip skt[4]= INVALID
2014-10-15 16:17:34 Local0.Info 192.168.1.130 M0: ++++ sip skt[5]= INVALID
2014-10-15 16:17:34 Local0.Info 192.168.1.130 M0: ++++ sip skt[5]= INVALID
2014-10-15 16:17:34 Local3.Debug 192.168.1.130 M3: IDBG[0]:6
2014-10-15 16:17:34 Local3.Debug 192.168.1.130 M3: IDBG[1]:7
2014-10-15 16:17:34 Local0.Info 192.168.1.130 M0: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:17:34 Local0.Info 192.168.1.130 M0: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:17:34 Local0.Info 192.168.1.130 M0: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:17:34 Local0.Info 192.168.1.130 M0: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:17:34 Local3.Debug 192.168.1.130 M0: [1]RegOK. NextReg in 594 (1)
2014-10-15 16:17:34 Local3.Debug 192.168.1.130 M0: [0]RegOK. NextReg in 594 (1)
2014-10-15 16:17:34 Local2.Debug 192.168.1.130 M0: [1]SubOK
2014-10-15 16:17:34 Local2.Debug 192.168.1.130 M0: NextSub in 2147483646 (1)
2014-10-15 16:17:34 Local2.Debug 192.168.1.130 M0: [0]SubOK
2014-10-15 16:17:34 Local2.Debug 192.168.1.130 M0: NextSub in 2147483646 (1)
2014-10-15 16:17:34 Local3.Debug 192.168.1.130 M3: fu:0:5fc2, 1a08 0001
2014-10-15 16:17:34 Local3.Debug 192.168.1.130 M1: fu:0:5c25, 1a08 0001
2014-10-15 16:17:34 Local3.Debug 192.168.1.130 M0: fu:1:e0bf, 1a08 0001
2014-10-15 16:17:34 Local3.Debug 192.168.1.130 M2: fu:0:d5e4, 1a08 0001
2014-10-15 16:17:35 Local2.Debug 192.168.1.130 M1: CID:OSI
2014-10-15 16:17:35 Local2.Debug 192.168.1.130 M1: CID:OSI
2014-10-15 16:17:35 Local2.Debug 192.168.1.130 M1: CID:OnHookTx Pol
2014-10-15 16:17:35 Local2.Debug 192.168.1.130 M1: CID:OnHookTx Pol
2014-10-15 16:17:35 Local2.Debug 192.168.1.130 M1: Start DTMF/FSK
2014-10-15 16:17:35 Local2.Debug 192.168.1.130 M1: Start DTMF/FSK
2014-10-15 16:17:36 Local2.Debug 192.168.1.130 M1: CID:DONE
2014-10-15 16:17:36 Local2.Debug 192.168.1.130 M1: CID:DONE
2014-10-15 16:17:36 Local3.Debug 192.168.1.130 M2: fu:0:d601, 0038 0445 0001
2014-10-15 16:17:36 Local3.Debug 192.168.1.130 M2: fu:0:d664, 03e4 05b0 0001
2014-10-15 16:17:36 Local2.Debug 192.168.1.130 M0: CID:OSI
2014-10-15 16:17:36 Local2.Debug 192.168.1.130 M0: CID:OSI
2014-10-15 16:17:36 Local2.Debug 192.168.1.130 M0: CID:OnHookTx Pol
2014-10-15 16:17:36 Local2.Debug 192.168.1.130 M0: CID:OnHookTx Pol
2014-10-15 16:17:36 Local2.Debug 192.168.1.130 M0: Start DTMF/FSK
2014-10-15 16:17:36 Local2.Debug 192.168.1.130 M0: Start DTMF/FSK
2014-10-15 16:17:37 Local2.Debug 192.168.1.130 M0: CID:DONE
2014-10-15 16:17:37 Local2.Debug 192.168.1.130 M0: CID:DONE
2014-10-15 16:17:37 Local3.Debug 192.168.1.130 M1: system request reboot
2014-10-15 16:17:40 Local3.Debug 192.168.1.130 M1: fu:0:5c42, 0038 0445 0001
2014-10-15 16:17:40 Local3.Debug 192.168.1.130 M1: fu:0:5ca1, 03e4 05b0 0001
2014-10-15 16:17:53 Local3.Debug 192.168.1.130 M1: mslink client task (1)  gw=192.168.2.1  ip=192.168.2.2
2014-10-15 16:17:54 Local3.Debug 192.168.1.130 M0: mslink module 3 restart detected
2014-10-15 16:17:54 Local3.Debug 192.168.1.130 M0: mslink module 1 restart detected
2014-10-15 16:17:54 Local3.Debug 192.168.1.130 M3: CC_init
2014-10-15 16:17:54 Local0.Info 192.168.1.130 M3: SLIC is si3215
2014-10-15 16:17:54 Local0.Info 192.168.1.130 M3: SLIC is si3215
2014-10-15 16:17:55 Local3.Debug 192.168.1.130 M3: fu:0:6057, 03e4 05b0 0001
2014-10-15 16:17:55 Local3.Debug 192.168.1.130 M3: IDBG[0]:6
2014-10-15 16:17:55 Local3.Debug 192.168.1.130 M3: IDBG[1]:7
2014-10-15 16:17:55 Local3.Debug 192.168.1.130 M3: IDBG: st-0
2014-10-15 16:17:56 Local3.Debug 192.168.1.130 M3: fs:008632:008717:131072
2014-10-15 16:17:56 Local3.Debug 192.168.1.130 M3: fls:af:1:0:0
2014-10-15 16:17:56 Local3.Debug 192.168.1.130 M3: fbr:0:3000:3000:06042:0008:0007:6.1.12
2014-10-15 16:17:56 Local3.Debug 192.168.1.130 M3: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:17:56 Local3.Debug 192.168.1.130 M3: fu:0:6069, 0003 0001
2014-10-15 16:17:56 Local3.Debug 192.168.1.130 M0: mslink  P:1568  V:8472
2014-10-15 16:17:57 Local3.Debug 192.168.1.130 M1: IDBG: st-0
2014-10-15 16:17:57 Local3.Debug 192.168.1.130 M1: fs:008752:008752:131072
2014-10-15 16:17:57 Local3.Debug 192.168.1.130 M1: fls:af:1:0:0
2014-10-15 16:17:57 Local3.Debug 192.168.1.130 M1: fbr:0:3000:3000:05ca1:0008:0007:6.1.12
2014-10-15 16:17:57 Local3.Debug 192.168.1.130 M1: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:17:57 Local3.Debug 192.168.1.130 M1: fu:0:5cb3, 0003 0001
2014-10-15 16:17:58 Local3.Debug 192.168.1.130 M0: mslink  P:1536  V:9471
2014-10-15 16:17:59 Local3.Debug 192.168.1.130 M2: mslink client task (2)  gw=192.168.2.1  ip=192.168.2.3
2014-10-15 16:17:59 Local3.Debug 192.168.1.130 M0: mslink module 2 restart detected
2014-10-15 16:18:01 Local3.Debug 192.168.1.130 M0: mslink  P:1403  V:7252
2014-10-15 16:18:02 Local3.Debug 192.168.1.130 M2: IDBG: st-0
2014-10-15 16:18:02 Local3.Debug 192.168.1.130 M2: fs:008710:008710:131072
2014-10-15 16:18:02 Local3.Debug 192.168.1.130 M2: fls:af:1:0:0
2014-10-15 16:18:02 Local3.Debug 192.168.1.130 M2: fbr:0:3000:3000:0d664:0008:0007:6.1.12
2014-10-15 16:18:02 Local3.Debug 192.168.1.130 M2: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:18:02 Local3.Debug 192.168.1.130 M2: fu:0:d676, 0003 0001
2014-10-15 16:18:06 Local3.Debug 192.168.1.130 M1: CC_init
2014-10-15 16:18:07 Local3.Debug 192.168.1.130 M2: CC_init
2014-10-15 16:18:07 Local0.Info 192.168.1.130 M1: SLIC is si3215
2014-10-15 16:18:07 Local0.Info 192.168.1.130 M1: SLIC is si3215
2014-10-15 16:18:07 Local3.Debug 192.168.1.130 M1: fu:0:5cc8, 03e4 05b0 0001
2014-10-15 16:18:07 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:18:07 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:18:07 Local3.Debug 192.168.1.130 M2: fu:0:d68b, 03e4 05b0 0001
2014-10-15 16:18:07 Local3.Debug 192.168.1.130 M1: IDBG[0]:4
2014-10-15 16:18:07 Local3.Debug 192.168.1.130 M1: IDBG[1]:5
2014-10-15 16:18:07 Local3.Debug 192.168.1.130 M2: IDBG[0]:6
2014-10-15 16:18:07 Local3.Debug 192.168.1.130 M2: IDBG[1]:5
2014-10-15 16:18:10 Local0.Info 192.168.1.130 M1: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:18:10 Local0.Info 192.168.1.130 M1: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:18:10 Local0.Info 192.168.1.130 M1: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:18:10 Local0.Info 192.168.1.130 M1: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:18:14 Local3.Debug 192.168.1.130 M0: fs:028955:029112:131072:093209327999
2014-10-15 16:18:14 Local3.Debug 192.168.1.130 M0: pbs 240512
2014-10-15 16:18:14 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Requesting resync http://xxxx.xml
2014-10-15 16:18:14 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Requesting resync http://xxxx.xml
2014-10-15 16:18:14 Local3.Debug 192.168.1.130 M0: FMM >>>> Requesting profile
2014-10-15 16:18:14 Local3.Debug 192.168.1.130 M0: content len (hdr) =146966
2014-10-15 16:18:15 Local3.Debug 192.168.1.130 M0: content len (pld) =146966
2014-10-15 16:18:15 Local3.Debug 192.168.1.130 M0: response code =200
2014-10-15 16:18:15 Local3.Debug 192.168.1.130 M2: fu:0:d69a, 1a08 0001
2014-10-15 16:18:15 Local3.Debug 192.168.1.130 M1: fu:0:5cd7, 1a08 0001
2014-10-15 16:18:15 Local3.Debug 192.168.1.130 M3: fu:0:6078, 1a08 0001
2014-10-15 16:18:15 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Successful resync http://xxxx.xml
2014-10-15 16:18:15 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Successful resync http://xxxx.xml
2014-10-15 16:18:15 Local3.Debug 192.168.1.130 M0: FMM >>>> Successful profile
2014-10-15 16:18:15 Local3.Debug 192.168.1.130 M0: fu:1:e0fa, 0038 0049 043c 0445 1a08 0001
2014-10-15 16:18:15 Local3.Debug 192.168.1.130 M0: system request reboot
2014-10-15 16:18:18 Local3.Debug 192.168.1.130 M1: system request reboot
2014-10-15 16:18:18 Local3.Debug 192.168.1.130 M3: system request reboot
2014-10-15 16:18:18 Local3.Debug 192.168.1.130 M2: system request reboot
2014-10-15 16:18:18 Local3.Debug 192.168.1.130 M0: fu:1:e1b0, 03e4 05b0 0001
2014-10-15 16:18:30 Local0.Info 192.168.1.130 M0: System started: ip@192.168.1.130, reboot reason:W4
2014-10-15 16:18:30 Local0.Info 192.168.1.130 M0: System started: ip@192.168.1.130, reboot reason:W4
2014-10-15 16:18:30 Local1.Debug 192.168.1.130 M0: subnet mask: 255.255.255.0
2014-10-15 16:18:30 Local1.Debug 192.168.1.130 M0: gateway ip: 192.168.1.1
2014-10-15 16:18:30 Local1.Debug 192.168.1.130 M0: dns servers(1):
2014-10-15 16:18:30 Local1.Debug 192.168.1.130 M0: 192.168.1.1 
2014-10-15 16:18:30 Local1.Debug 192.168.1.130 M0:  
2014-10-15 16:18:30 Local3.Debug 192.168.1.130 M0: mslink server task up (0)
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: IDBG: st-0
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fs:029103:029103:131072
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fls:af:1:0:0
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fbr:1:3000:3000:1e1b0:0020:0021:6.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:01:0:0002:upg:app:1:5.1.9(a)
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:02:0:0003:upg:app:2:5.1.9(a)
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:03:0:0004:upg:app:0:5.1.10
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:04:0:0005:upg:app:1:5.1.10
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:05:0:0006:upg:app:2:5.1.10
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:06:0:0007:upg:app:0:5.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:07:0:0008:upg:app:1:5.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:08:0:0009:upg:app:2:5.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:09:0:000a:upg:app:0:6.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:0a:0:000b:upg:app:1:6.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:0b:0:000c:upg:app:2:6.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:0c:0:000d:upg:app:0:5.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:0d:0:000e:upg:app:1:5.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:0e:0:000f:upg:app:2:5.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:0f:0:0010:upg:app:0:6.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:10:0:0011:upg:app:1:6.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: fhs:11:0:0012:upg:app:2:6.1.12
2014-10-15 16:18:37 Local3.Debug 192.168.1.130 M0: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:18:38 Local3.Debug 192.168.1.130 M0: fu:1:e1c2, 0003 0001
2014-10-15 16:18:39 Local0.Info 192.168.1.130 M1: System started: ip@192.168.2.2, reboot reason:H0
2014-10-15 16:18:39 Local0.Info 192.168.1.130 M1: System started: ip@192.168.2.2, reboot reason:H0
2014-10-15 16:18:39 Local1.Debug 192.168.1.130 M1: subnet mask: 255.255.255.0
2014-10-15 16:18:39 Local1.Debug 192.168.1.130 M1: gateway ip: 192.168.2.1
2014-10-15 16:18:39 Local1.Debug 192.168.1.130 M1: dns servers(1):
2014-10-15 16:18:39 Local1.Debug 192.168.1.130 M1: 192.168.1.1 
2014-10-15 16:18:39 Local1.Debug 192.168.1.130 M1:  
2014-10-15 16:18:40 Local3.Debug 192.168.1.130 M1: mslink server task up (1)
2014-10-15 16:18:45 Local3.Debug 192.168.1.130 M1: IDBG: st-0
2014-10-15 16:18:45 Local3.Debug 192.168.1.130 M1: fs:008665:008665:131072
2014-10-15 16:18:45 Local3.Debug 192.168.1.130 M1: fls:af:1:0:0
2014-10-15 16:18:45 Local3.Debug 192.168.1.130 M1: fbr:0:3000:3000:05cd7:0008:0007:6.1.12
2014-10-15 16:18:45 Local3.Debug 192.168.1.130 M1: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:18:45 Local3.Debug 192.168.1.130 M1: fu:0:5ce9, 0003 0001
2014-10-15 16:18:45 Local3.Debug 192.168.1.130 M1: mslink client task (1)  gw=192.168.2.1  ip=192.168.2.2
2014-10-15 16:18:46 Local3.Debug 192.168.1.130 M0: MSLINK module 1 is at 192.168.2.2
2014-10-15 16:18:46 Local3.Debug 192.168.1.130 M0: MSLINK code module 1: 0(32) sn:0
2014-10-15 16:18:49 Local0.Info 192.168.1.130 M2: System started: ip@192.168.2.3, reboot reason:H0
2014-10-15 16:18:49 Local0.Info 192.168.1.130 M2: System started: ip@192.168.2.3, reboot reason:H0
2014-10-15 16:18:49 Local1.Debug 192.168.1.130 M2: subnet mask: 255.255.255.0
2014-10-15 16:18:49 Local1.Debug 192.168.1.130 M2: gateway ip: 192.168.2.1
2014-10-15 16:18:49 Local1.Debug 192.168.1.130 M2: dns servers(1):
2014-10-15 16:18:49 Local1.Debug 192.168.1.130 M2: 192.168.1.1 
2014-10-15 16:18:49 Local1.Debug 192.168.1.130 M2:  
2014-10-15 16:18:50 Local3.Debug 192.168.1.130 M2: mslink server task up (2)
2014-10-15 16:18:54 Local3.Debug 192.168.1.130 M2: mslink client task (2)  gw=192.168.2.1  ip=192.168.2.3
2014-10-15 16:18:54 Local3.Debug 192.168.1.130 M0: MSLINK module 2 is at 192.168.2.3
2014-10-15 16:18:54 Local3.Debug 192.168.1.130 M0: MSLINK code module 2: 0(32) sn:0
2014-10-15 16:18:56 Local3.Debug 192.168.1.130 M2: IDBG: st-0
2014-10-15 16:18:56 Local3.Debug 192.168.1.130 M2: fs:008619:008632:131072
2014-10-15 16:18:56 Local3.Debug 192.168.1.130 M2: fls:af:1:0:0
2014-10-15 16:18:56 Local3.Debug 192.168.1.130 M2: fbr:0:3000:3000:0d69a:0008:0007:6.1.12
2014-10-15 16:18:56 Local3.Debug 192.168.1.130 M2: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:18:56 Local3.Debug 192.168.1.130 M2: fu:0:d6ac, 0003 0001
2014-10-15 16:18:57 Local0.Info 192.168.1.130 M3: System started: ip@192.168.2.4, reboot reason:H0
2014-10-15 16:18:57 Local0.Info 192.168.1.130 M3: System started: ip@192.168.2.4, reboot reason:H0
2014-10-15 16:18:57 Local1.Debug 192.168.1.130 M3: subnet mask: 255.255.255.0
2014-10-15 16:18:57 Local1.Debug 192.168.1.130 M3: gateway ip: 192.168.2.1
2014-10-15 16:18:57 Local1.Debug 192.168.1.130 M3: dns servers(1):
2014-10-15 16:18:57 Local1.Debug 192.168.1.130 M3: 192.168.1.1 
2014-10-15 16:18:57 Local1.Debug 192.168.1.130 M3:  
2014-10-15 16:18:57 Local3.Debug 192.168.1.130 M3: fs:008619:008633:131072
2014-10-15 16:18:57 Local3.Debug 192.168.1.130 M3: fls:af:1:0:0
2014-10-15 16:18:57 Local3.Debug 192.168.1.130 M3: fbr:0:3000:3000:06078:0008:0007:6.1.12
2014-10-15 16:18:57 Local3.Debug 192.168.1.130 M3: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:18:57 Local3.Debug 192.168.1.130 M3: fu:0:608a, 0003 0001
2014-10-15 16:18:59 Local3.Debug 192.168.1.130 M3: mslink server task up (3)
2014-10-15 16:19:03 Local3.Debug 192.168.1.130 M0: mslink  P:8277  V:37570
2014-10-15 16:19:04 Local3.Debug 192.168.1.130 M3: mslink client task (3)  gw=192.168.2.1  ip=192.168.2.4
2014-10-15 16:19:04 Local3.Debug 192.168.1.130 M0: MSLINK module 3 is at 192.168.2.4
2014-10-15 16:19:04 Local3.Debug 192.168.1.130 M0: MSLINK code module 3: 0(32) sn:0
2014-10-15 16:19:05 Local3.Debug 192.168.1.130 M0: mslink  P:703  V:3620
2014-10-15 16:19:07 Local3.Debug 192.168.1.130 M0: mslink  P:1531  V:8029
2014-10-15 16:19:09 Local3.Debug 192.168.1.130 M0: mslink  P:1330  V:5520
2014-10-15 16:19:12 Local3.Debug 192.168.1.130 M3: CC_init
2014-10-15 16:19:12 Local0.Info 192.168.1.130 M3: SLIC is si3215
2014-10-15 16:19:12 Local0.Info 192.168.1.130 M3: SLIC is si3215
2014-10-15 16:19:12 Local3.Debug 192.168.1.130 M3: IDBG[0]:6
2014-10-15 16:19:12 Local3.Debug 192.168.1.130 M3: IDBG[1]:7
2014-10-15 16:19:14 Local3.Debug 192.168.1.130 M1: CC_init
2014-10-15 16:19:14 Local0.Info 192.168.1.130 M1: SLIC is si3215
2014-10-15 16:19:14 Local0.Info 192.168.1.130 M1: SLIC is si3215
2014-10-15 16:19:15 Local3.Debug 192.168.1.130 M2: CC_init
2014-10-15 16:19:15 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:19:15 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:19:15 Local3.Debug 192.168.1.130 M1: IDBG[0]:4
2014-10-15 16:19:15 Local3.Debug 192.168.1.130 M1: IDBG[1]:6
2014-10-15 16:19:15 Local3.Debug 192.168.1.130 M0: CC_init
2014-10-15 16:19:15 Local3.Debug 192.168.1.130 M2: IDBG[0]:6
2014-10-15 16:19:15 Local3.Debug 192.168.1.130 M2: IDBG[1]:5
2014-10-15 16:19:16 Local0.Info 192.168.1.130 M0: SLIC is si3215
2014-10-15 16:19:16 Local0.Info 192.168.1.130 M0: SLIC is si3215
2014-10-15 16:19:16 Local3.Debug 192.168.1.130 M0: fu:1:e1d7, 03e4 05b0 0001
2014-10-15 16:19:16 Local0.Info 192.168.1.130 M1: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:19:16 Local0.Info 192.168.1.130 M1: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:19:16 Local0.Info 192.168.1.130 M1: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:19:16 Local0.Info 192.168.1.130 M1: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:19:16 Local3.Debug 192.168.1.130 M0: IDBG[0]:6
2014-10-15 16:19:16 Local3.Debug 192.168.1.130 M0: IDBG[1]:4
2014-10-15 16:19:16 Local3.Debug 192.168.1.130 M1: [0]RegOK. NextReg in 594 (1)
2014-10-15 16:19:16 Local3.Debug 192.168.1.130 M1: [1]RegOK. NextReg in 594 (1)
2014-10-15 16:19:16 Local2.Debug 192.168.1.130 M1: [0]SubOK
2014-10-15 16:19:16 Local2.Debug 192.168.1.130 M1: NextSub in 2147483646 (1)
2014-10-15 16:19:16 Local2.Debug 192.168.1.130 M1: [1]SubOK
2014-10-15 16:19:16 Local2.Debug 192.168.1.130 M1: NextSub in 2147483646 (1)
2014-10-15 16:19:18 Local2.Debug 192.168.1.130 M1: CID:OSI
2014-10-15 16:19:18 Local2.Debug 192.168.1.130 M1: CID:OSI
2014-10-15 16:19:18 Local2.Debug 192.168.1.130 M1: CID:OnHookTx Pol
2014-10-15 16:19:18 Local2.Debug 192.168.1.130 M1: CID:OnHookTx Pol
2014-10-15 16:19:19 Local2.Debug 192.168.1.130 M1: Start DTMF/FSK
2014-10-15 16:19:19 Local2.Debug 192.168.1.130 M1: Start DTMF/FSK
2014-10-15 16:19:19 Local0.Info 192.168.1.130 M0: ++++ sip skt[3]= INVALID
2014-10-15 16:19:19 Local0.Info 192.168.1.130 M0: ++++ sip skt[3]= INVALID
2014-10-15 16:19:19 Local0.Info 192.168.1.130 M0: ++++ sip skt[4]= INVALID
2014-10-15 16:19:19 Local0.Info 192.168.1.130 M0: ++++ sip skt[4]= INVALID
2014-10-15 16:19:19 Local2.Debug 192.168.1.130 M1: CID:DONE
2014-10-15 16:19:19 Local0.Info 192.168.1.130 M0: ++++ sip skt[5]= INVALID
2014-10-15 16:19:19 Local0.Info 192.168.1.130 M0: ++++ sip skt[5]= INVALID
2014-10-15 16:19:19 Local2.Debug 192.168.1.130 M1: CID:DONE
2014-10-15 16:19:19 Local0.Info 192.168.1.130 M0: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:19:19 Local0.Info 192.168.1.130 M0: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:19:19 Local0.Info 192.168.1.130 M0: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:19:19 Local0.Info 192.168.1.130 M0: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:19:19 Local3.Debug 192.168.1.130 M0: [0]RegOK. NextReg in 594 (1)
2014-10-15 16:19:19 Local3.Debug 192.168.1.130 M0: [1]RegOK. NextReg in 594 (1)
2014-10-15 16:19:19 Local2.Debug 192.168.1.130 M0: [0]SubOK
2014-10-15 16:19:19 Local2.Debug 192.168.1.130 M0: NextSub in 2147483646 (1)
2014-10-15 16:19:19 Local2.Debug 192.168.1.130 M0: [1]SubOK
2014-10-15 16:19:19 Local2.Debug 192.168.1.130 M0: NextSub in 2147483646 (1)
2014-10-15 16:19:20 Local3.Debug 192.168.1.130 M0: fu:1:e1f3, 1a08 0001
2014-10-15 16:19:20 Local3.Debug 192.168.1.130 M3: fu:0:60a6, 1a08 0001
2014-10-15 16:19:20 Local3.Debug 192.168.1.130 M2: fu:0:d6c8, 1a08 0001
2014-10-15 16:19:20 Local3.Debug 192.168.1.130 M1: fu:0:5d05, 1a08 0001
2014-10-15 16:19:21 Local2.Debug 192.168.1.130 M0: CID:OSI
2014-10-15 16:19:21 Local2.Debug 192.168.1.130 M0: CID:OSI
2014-10-15 16:19:22 Local2.Debug 192.168.1.130 M0: CID:OnHookTx Pol
2014-10-15 16:19:22 Local2.Debug 192.168.1.130 M0: CID:OnHookTx Pol
2014-10-15 16:19:22 Local2.Debug 192.168.1.130 M0: Start DTMF/FSK
2014-10-15 16:19:22 Local2.Debug 192.168.1.130 M0: Start DTMF/FSK
2014-10-15 16:19:22 Local2.Debug 192.168.1.130 M0: CID:DONE
2014-10-15 16:19:22 Local2.Debug 192.168.1.130 M0: CID:DONE
2014-10-15 16:19:23 Local3.Debug 192.168.1.130 M3: system request reboot
2014-10-15 16:19:23 Local3.Debug 192.168.1.130 M1: system request reboot
2014-10-15 16:19:23 Local3.Debug 192.168.1.130 M2: system request reboot
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M3: fu:0:60c3, 0038 0445 0001
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M1: fu:0:5d22, 0038 0445 0001
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M2: fu:0:d6e5, 0038 0445 0001
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M0: PRV pc=1
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M3: fu:0:6126, 03e4 05b0 0001
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M0: fs:028955:029114:131072:093209327999
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M0: pbs 240512
2014-10-15 16:19:26 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Requesting resync http://xxxx.xml
2014-10-15 16:19:26 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Requesting resync http://xxxx.xml
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M0: FMM >>>> Requesting profile
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M1: fu:0:5d85, 03e4 05b0 0001
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M2: fu:0:d746, 03e4 05b0 0001
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M0: content len (hdr) =146966
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M0: content len (pld) =146966
2014-10-15 16:19:26 Local3.Debug 192.168.1.130 M0: response code =200
2014-10-15 16:19:27 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Successful resync http://xxxx.xml
2014-10-15 16:19:27 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Successful resync http://xxxx.xml
2014-10-15 16:19:27 Local3.Debug 192.168.1.130 M0: FMM >>>> Successful profile
2014-10-15 16:19:27 Local3.Debug 192.168.1.130 M0: fu:1:e22e, 0038 0049 043c 0445 1a08 0001
2014-10-15 16:19:27 Local3.Debug 192.168.1.130 M0: system request reboot
2014-10-15 16:19:30 Local3.Debug 192.168.1.130 M0: fu:1:e2e2, 03e4 05b0 0001
2014-10-15 16:19:39 Local0.Info 192.168.1.130 M0: System started: ip@192.168.1.130, reboot reason:W4
2014-10-15 16:19:39 Local0.Info 192.168.1.130 M0: System started: ip@192.168.1.130, reboot reason:W4
2014-10-15 16:19:39 Local1.Debug 192.168.1.130 M0: subnet mask: 255.255.255.0
2014-10-15 16:19:39 Local1.Debug 192.168.1.130 M0: gateway ip: 192.168.1.1
2014-10-15 16:19:39 Local1.Debug 192.168.1.130 M0: dns servers(1):
2014-10-15 16:19:39 Local1.Debug 192.168.1.130 M0: 192.168.1.1 
2014-10-15 16:19:39 Local1.Debug 192.168.1.130 M0:  
2014-10-15 16:19:39 Local3.Debug 192.168.1.130 M0: mslink server task up (0)
2014-10-15 16:19:47 Local3.Debug 192.168.1.130 M0: IDBG: st-0
2014-10-15 16:19:47 Local3.Debug 192.168.1.130 M0: fs:029101:029101:131072
2014-10-15 16:19:47 Local3.Debug 192.168.1.130 M0: fls:af:1:0:0
2014-10-15 16:19:47 Local3.Debug 192.168.1.130 M0: fbr:1:3000:3000:1e2e2:0020:0021:6.1.12
2014-10-15 16:19:47 Local3.Debug 192.168.1.130 M0: fhs:01:0:0002:upg:app:1:5.1.9(a)
2014-10-15 16:19:47 Local3.Debug 192.168.1.130 M0: fhs:02:0:0003:upg:app:2:5.1.9(a)
2014-10-15 16:19:47 Local3.Debug 192.168.1.130 M0: fhs:03:0:0004:upg:app:0:5.1.10
2014-10-15 16:19:47 Local3.Debug 192.168.1.130 M0: fhs:04:0:0005:upg:app:1:5.1.10
2014-10-15 16:19:47 Local3.Debug 192.168.1.130 M0: fhs:05:0:0006:upg:app:2:5.1.10
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:06:0:0007:upg:app:0:5.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:07:0:0008:upg:app:1:5.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:08:0:0009:upg:app:2:5.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:09:0:000a:upg:app:0:6.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:0a:0:000b:upg:app:1:6.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:0b:0:000c:upg:app:2:6.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:0c:0:000d:upg:app:0:5.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:0d:0:000e:upg:app:1:5.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:0e:0:000f:upg:app:2:5.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:0f:0:0010:upg:app:0:6.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:10:0:0011:upg:app:1:6.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fhs:11:0:0012:upg:app:2:6.1.12
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:19:48 Local3.Debug 192.168.1.130 M0: fu:1:e2f4, 0003 0001
2014-10-15 16:19:53 Local3.Debug 192.168.1.130 M1: mslink client task (1)  gw=192.168.2.1  ip=192.168.2.2
2014-10-15 16:19:54 Local3.Debug 192.168.1.130 M0: MSLINK module 1 is at 192.168.2.2
2014-10-15 16:19:54 Local3.Debug 192.168.1.130 M0: MSLINK code module 1: 0(32) sn:0
2014-10-15 16:19:55 Local3.Debug 192.168.1.130 M1: fu:0:5d94, 1a08 0001
2014-10-15 16:19:57 Local3.Debug 192.168.1.130 M1: IDBG: st-0
2014-10-15 16:19:57 Local3.Debug 192.168.1.130 M1: fs:008743:008764:131072
2014-10-15 16:19:57 Local3.Debug 192.168.1.130 M1: fls:af:1:0:0
2014-10-15 16:19:57 Local3.Debug 192.168.1.130 M1: fbr:0:3000:3000:05d85:0008:0007:6.1.12
2014-10-15 16:19:57 Local3.Debug 192.168.1.130 M1: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:19:57 Local3.Debug 192.168.1.130 M1: fu:0:5da6, 0003 0001
2014-10-15 16:20:00 Local3.Debug 192.168.1.130 M3: fs:008710:008710:131072
2014-10-15 16:20:00 Local3.Debug 192.168.1.130 M3: fls:af:1:0:0
2014-10-15 16:20:00 Local3.Debug 192.168.1.130 M3: fbr:0:3000:3000:06126:0008:0007:6.1.12
2014-10-15 16:20:00 Local3.Debug 192.168.1.130 M3: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:20:00 Local3.Debug 192.168.1.130 M3: fu:0:6138, 0003 0001
2014-10-15 16:20:00 Local3.Debug 192.168.1.130 M0: MSLINK module 3 is at 192.168.2.4
2014-10-15 16:20:00 Local3.Debug 192.168.1.130 M0: MSLINK code module 3: 0(32) sn:0
2014-10-15 16:20:00 Local3.Debug 192.168.1.130 M3: fu:0:6147, 1a08 0001
2014-10-15 16:20:03 Local3.Debug 192.168.1.130 M2: mslink client task (2)  gw=192.168.2.1  ip=192.168.2.3
2014-10-15 16:20:04 Local3.Debug 192.168.1.130 M0: MSLINK module 2 is at 192.168.2.3
2014-10-15 16:20:04 Local3.Debug 192.168.1.130 M0: MSLINK code module 2: 0(32) sn:0
2014-10-15 16:20:07 Local3.Debug 192.168.1.130 M2: IDBG: st-0
2014-10-15 16:20:07 Local3.Debug 192.168.1.130 M2: fu:0:d755, 1a08 0001
2014-10-15 16:20:07 Local3.Debug 192.168.1.130 M2: fs:008695:008716:131072
2014-10-15 16:20:07 Local3.Debug 192.168.1.130 M2: fls:af:1:0:0
2014-10-15 16:20:07 Local3.Debug 192.168.1.130 M2: fbr:0:3000:3000:0d746:0008:0007:6.1.12
2014-10-15 16:20:07 Local3.Debug 192.168.1.130 M2: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:20:08 Local3.Debug 192.168.1.130 M2: fu:0:d767, 0003 0001
2014-10-15 16:20:15 Local3.Debug 192.168.1.130 M0: mslink  P:11401  V:55015
2014-10-15 16:20:16 Local3.Debug 192.168.1.130 M1: system request reboot
2014-10-15 16:20:16 Local3.Debug 192.168.1.130 M3: system request reboot
2014-10-15 16:20:16 Local3.Debug 192.168.1.130 M2: system request reboot
2014-10-15 16:20:16 Local3.Debug 192.168.1.130 M2: CC_init
2014-10-15 16:20:16 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:20:16 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:20:16 Local3.Debug 192.168.1.130 M2: fu:0:d77c, 03e4 05b0 0001
2014-10-15 16:20:17 Local3.Debug 192.168.1.130 M0: CC_init
2014-10-15 16:20:17 Local3.Debug 192.168.1.130 M2: IDBG[0]:6
2014-10-15 16:20:17 Local3.Debug 192.168.1.130 M2: IDBG[1]:6
2014-10-15 16:20:17 Local0.Info 192.168.1.130 M0: SLIC is si3215
2014-10-15 16:20:17 Local0.Info 192.168.1.130 M0: SLIC is si3215
2014-10-15 16:20:17 Local3.Debug 192.168.1.130 M0: fu:1:e309, 03e4 05b0 0001
2014-10-15 16:20:18 Local3.Debug 192.168.1.130 M0: IDBG[0]:6
2014-10-15 16:20:18 Local3.Debug 192.168.1.130 M0: IDBG[1]:4
2014-10-15 16:20:18 Local3.Debug 192.168.1.130 M3: CC_init
2014-10-15 16:20:18 Local0.Info 192.168.1.130 M3: SLIC is si3215
2014-10-15 16:20:18 Local0.Info 192.168.1.130 M3: SLIC is si3215
2014-10-15 16:20:18 Local3.Debug 192.168.1.130 M3: fu:0:615c, 03e4 05b0 0001
2014-10-15 16:20:19 Local3.Debug 192.168.1.130 M3: IDBG[0]:6
2014-10-15 16:20:19 Local3.Debug 192.168.1.130 M3: IDBG[1]:7
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M0: ++++ sip skt[3]= INVALID
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M0: ++++ sip skt[3]= INVALID
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M0: ++++ sip skt[4]= INVALID
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M0: ++++ sip skt[4]= INVALID
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M0: ++++ sip skt[5]= INVALID
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M0: ++++ sip skt[5]= INVALID
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M0: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M0: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M0: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M0: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:21 Local3.Debug 192.168.1.130 M1: CC_init
2014-10-15 16:20:21 Local3.Debug 192.168.1.130 M2: fu:0:d799, 0038 0445 0001
2014-10-15 16:20:21 Local3.Debug 192.168.1.130 M0: [0]RegOK. NextReg in 594 (1)
2014-10-15 16:20:21 Local3.Debug 192.168.1.130 M0: [1]RegOK. NextReg in 594 (1)
2014-10-15 16:20:21 Local2.Debug 192.168.1.130 M0: [0]SubOK
2014-10-15 16:20:21 Local2.Debug 192.168.1.130 M0: NextSub in 2147483646 (1)
2014-10-15 16:20:21 Local2.Debug 192.168.1.130 M0: [1]SubOK
2014-10-15 16:20:21 Local2.Debug 192.168.1.130 M0: NextSub in 2147483646 (1)
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M1: SLIC is si3215
2014-10-15 16:20:21 Local0.Info 192.168.1.130 M1: SLIC is si3215
2014-10-15 16:20:21 Local3.Debug 192.168.1.130 M2: fu:0:d7fa, 03e4 05b0 0001
2014-10-15 16:20:21 Local3.Debug 192.168.1.130 M0: fu:1:e325, 1a08 0001
2014-10-15 16:20:21 Local3.Debug 192.168.1.130 M1: fu:0:5dd0, 03e4 05b0 1a08 0001
2014-10-15 16:20:21 Local3.Debug 192.168.1.130 M1: IDBG[0]:4
2014-10-15 16:20:21 Local3.Debug 192.168.1.130 M1: IDBG[1]:5
2014-10-15 16:20:23 Local2.Debug 192.168.1.130 M0: CID:OSI
2014-10-15 16:20:23 Local2.Debug 192.168.1.130 M0: CID:OSI
2014-10-15 16:20:23 Local2.Debug 192.168.1.130 M0: CID:OnHookTx Pol
2014-10-15 16:20:23 Local2.Debug 192.168.1.130 M0: CID:OnHookTx Pol
2014-10-15 16:20:24 Local2.Debug 192.168.1.130 M0: Start DTMF/FSK
2014-10-15 16:20:24 Local2.Debug 192.168.1.130 M0: Start DTMF/FSK
2014-10-15 16:20:24 Local2.Debug 192.168.1.130 M0: CID:DONE
2014-10-15 16:20:24 Local2.Debug 192.168.1.130 M0: CID:DONE
2014-10-15 16:20:25 Local0.Info 192.168.1.130 M1: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:25 Local0.Info 192.168.1.130 M1: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:25 Local0.Info 192.168.1.130 M1: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:25 Local0.Info 192.168.1.130 M1: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:26 Local3.Debug 192.168.1.130 M1: fu:0:5ded, 0038 0445 0001
2014-10-15 16:20:26 Local3.Debug 192.168.1.130 M1: fu:0:5e50, 03e4 05b0 0001
2014-10-15 16:20:39 Local3.Debug 192.168.1.130 M1: mslink client task (1)  gw=192.168.2.1  ip=192.168.2.2
2014-10-15 16:20:39 Local3.Debug 192.168.1.130 M0: mslink module 3 restart detected
2014-10-15 16:20:40 Local3.Debug 192.168.1.130 M3: CC_init
2014-10-15 16:20:40 Local0.Info 192.168.1.130 M3: SLIC is si3215
2014-10-15 16:20:40 Local0.Info 192.168.1.130 M3: SLIC is si3215
2014-10-15 16:20:40 Local3.Debug 192.168.1.130 M3: fu:0:620d, 03e4 05b0 0001
2014-10-15 16:20:40 Local3.Debug 192.168.1.130 M0: mslink module 1 restart detected
2014-10-15 16:20:40 Local3.Debug 192.168.1.130 M3: IDBG[0]:6
2014-10-15 16:20:40 Local3.Debug 192.168.1.130 M3: IDBG[1]:7
2014-10-15 16:20:41 Local3.Debug 192.168.1.130 M3: IDBG: st-0
2014-10-15 16:20:41 Local3.Debug 192.168.1.130 M3: fs:008632:008717:131072
2014-10-15 16:20:41 Local3.Debug 192.168.1.130 M3: fls:af:1:0:0
2014-10-15 16:20:41 Local3.Debug 192.168.1.130 M3: fbr:0:3000:3000:061f8:0008:0007:6.1.12
2014-10-15 16:20:41 Local3.Debug 192.168.1.130 M3: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:20:41 Local3.Debug 192.168.1.130 M3: fu:0:621f, 0003 0001
2014-10-15 16:20:41 Local3.Debug 192.168.1.130 M0: mslink  P:1569  V:8692
2014-10-15 16:20:42 Local3.Debug 192.168.1.130 M2: fu:0:d816, 1a08 0001
2014-10-15 16:20:43 Local3.Debug 192.168.1.130 M1: IDBG: st-0
2014-10-15 16:20:43 Local3.Debug 192.168.1.130 M1: fs:008756:008756:131072
2014-10-15 16:20:43 Local3.Debug 192.168.1.130 M1: fls:af:1:0:0
2014-10-15 16:20:43 Local3.Debug 192.168.1.130 M1: fbr:0:3000:3000:05e50:0008:0007:6.1.12
2014-10-15 16:20:43 Local3.Debug 192.168.1.130 M1: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:20:43 Local3.Debug 192.168.1.130 M1: fu:0:5e62, 0003 0001
2014-10-15 16:20:43 Local3.Debug 192.168.1.130 M0: mslink  P:1463  V:8239
2014-10-15 16:20:44 Local3.Debug 192.168.1.130 M2: mslink client task (2)  gw=192.168.2.1  ip=192.168.2.3
2014-10-15 16:20:45 Local3.Debug 192.168.1.130 M0: mslink module 2 restart detected
2014-10-15 16:20:45 Local3.Debug 192.168.1.130 M0: mslink  P:445  V:2865
2014-10-15 16:20:47 Local3.Debug 192.168.1.130 M0: mslink  P:1233  V:6257
2014-10-15 16:20:47 Local3.Debug 192.168.1.130 M2: IDBG: st-0
2014-10-15 16:20:48 Local3.Debug 192.168.1.130 M2: fs:008708:008716:131072
2014-10-15 16:20:48 Local3.Debug 192.168.1.130 M2: fls:af:1:0:0
2014-10-15 16:20:48 Local3.Debug 192.168.1.130 M2: fbr:0:3000:3000:0d7fa:0008:0007:6.1.12
2014-10-15 16:20:48 Local3.Debug 192.168.1.130 M2: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:20:48 Local3.Debug 192.168.1.130 M2: fu:0:d828, 0003 0001
2014-10-15 16:20:48 Local3.Debug 192.168.1.130 M2: system request reboot
2014-10-15 16:20:52 Local3.Debug 192.168.1.130 M2: CC_init
2014-10-15 16:20:52 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:20:52 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:20:52 Local3.Debug 192.168.1.130 M2: fu:0:d83d, 03e4 05b0 0001
2014-10-15 16:20:52 Local3.Debug 192.168.1.130 M1: CC_init
2014-10-15 16:20:52 Local0.Info 192.168.1.130 M1: SLIC is si3215
2014-10-15 16:20:52 Local0.Info 192.168.1.130 M1: SLIC is si3215
2014-10-15 16:20:52 Local3.Debug 192.168.1.130 M1: fu:0:5e77, 03e4 05b0 0001
2014-10-15 16:20:52 Local3.Debug 192.168.1.130 M2: IDBG[0]:6
2014-10-15 16:20:52 Local3.Debug 192.168.1.130 M2: IDBG[1]:5
2014-10-15 16:20:53 Local3.Debug 192.168.1.130 M1: IDBG[0]:4
2014-10-15 16:20:53 Local3.Debug 192.168.1.130 M1: IDBG[1]:5
2014-10-15 16:20:56 Local0.Info 192.168.1.130 M1: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:56 Local0.Info 192.168.1.130 M1: [0]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:56 Local0.Info 192.168.1.130 M1: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:56 Local0.Info 192.168.1.130 M1: [1]Reg Addr Change(0) 0:0->c7606e01:5060
2014-10-15 16:20:56 Local3.Debug 192.168.1.130 M2: fu:0:d85a, 0038 0445 0001
2014-10-15 16:20:56 Local3.Debug 192.168.1.130 M2: fu:0:d8bb, 03e4 05b0 0001
2014-10-15 16:21:15 Local3.Debug 192.168.1.130 M3: system request reboot
2014-10-15 16:21:18 Local3.Debug 192.168.1.130 M3: fu:0:623c, 0038 0445 0001
2014-10-15 16:21:18 Local3.Debug 192.168.1.130 M3: fu:0:62a2, 03e4 05b0 0001
2014-10-15 16:21:19 Local3.Debug 192.168.1.130 M2: mslink client task (2)  gw=192.168.2.1  ip=192.168.2.3
2014-10-15 16:21:20 Local3.Debug 192.168.1.130 M0: mslink module 2 restart detected
2014-10-15 16:21:20 Local3.Debug 192.168.1.130 M0: mslink  P:256  V:1750
2014-10-15 16:21:22 Local3.Debug 192.168.1.130 M0: mslink  P:1211  V:4603
2014-10-15 16:21:23 Local3.Debug 192.168.1.130 M2: IDBG: st-0
2014-10-15 16:21:23 Local3.Debug 192.168.1.130 M2: fs:008708:008708:131072
2014-10-15 16:21:23 Local3.Debug 192.168.1.130 M2: fls:af:1:0:0
2014-10-15 16:21:23 Local3.Debug 192.168.1.130 M2: fbr:0:3000:3000:0d8bb:0008:0007:6.1.12
2014-10-15 16:21:23 Local3.Debug 192.168.1.130 M2: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:21:23 Local3.Debug 192.168.1.130 M2: fu:0:d8cd, 0003 0001
2014-10-15 16:21:27 Local3.Debug 192.168.1.130 M2: CC_init
2014-10-15 16:21:27 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:21:27 Local0.Info 192.168.1.130 M2: SLIC is si3215
2014-10-15 16:21:27 Local3.Debug 192.168.1.130 M2: fu:0:d8e2, 03e4 05b0 0001
2014-10-15 16:21:28 Local3.Debug 192.168.1.130 M1: [0]RegFail. Retry in 30
2014-10-15 16:21:28 Local3.Debug 192.168.1.130 M1: [1]RegFail. Retry in 30
2014-10-15 16:21:28 Local3.Debug 192.168.1.130 M2: IDBG[0]:6
2014-10-15 16:21:28 Local3.Debug 192.168.1.130 M2: IDBG[1]:5
2014-10-15 16:21:29 Local0.Info 192.168.1.130 M3: System started: ip@192.168.2.4, reboot reason:C200
2014-10-15 16:21:29 Local0.Info 192.168.1.130 M3: System started: ip@192.168.2.4, reboot reason:C200
2014-10-15 16:21:29 Local1.Debug 192.168.1.130 M3: subnet mask: 255.255.255.0
2014-10-15 16:21:29 Local1.Debug 192.168.1.130 M3: gateway ip: 192.168.2.1
2014-10-15 16:21:29 Local1.Debug 192.168.1.130 M3: dns servers(1):
2014-10-15 16:21:29 Local1.Debug 192.168.1.130 M3: 192.168.1.1 
2014-10-15 16:21:29 Local1.Debug 192.168.1.130 M3:  
2014-10-15 16:21:30 Local2.Debug 192.168.1.130 M1: CID:OSI
2014-10-15 16:21:30 Local2.Debug 192.168.1.130 M1: CID:OSI
2014-10-15 16:21:30 Local2.Debug 192.168.1.130 M1: CID:OnHookTx Pol
2014-10-15 16:21:30 Local2.Debug 192.168.1.130 M1: CID:OnHookTx Pol
2014-10-15 16:21:30 Local2.Debug 192.168.1.130 M1: Start DTMF/FSK
2014-10-15 16:21:30 Local2.Debug 192.168.1.130 M1: Start DTMF/FSK
2014-10-15 16:21:31 Local3.Debug 192.168.1.130 M3: mslink server task up (3)
2014-10-15 16:21:31 Local2.Debug 192.168.1.130 M1: CID:DONE
2014-10-15 16:21:31 Local2.Debug 192.168.1.130 M1: CID:DONE
2014-10-15 16:21:34 Local3.Debug 192.168.1.130 M0: PRV pc=1
2014-10-15 16:21:34 Local3.Debug 192.168.1.130 M0: fs:028955:029112:131072:093209327999
2014-10-15 16:21:34 Local3.Debug 192.168.1.130 M0: pbs 240512
2014-10-15 16:21:34 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Requesting resync http://xxxx.xml
2014-10-15 16:21:34 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Requesting resync http://xxxx.xml
2014-10-15 16:21:34 Local3.Debug 192.168.1.130 M0: FMM >>>> Requesting profile
2014-10-15 16:21:34 Local3.Debug 192.168.1.130 M0: content len (hdr) =146966
2014-10-15 16:21:34 Local3.Debug 192.168.1.130 M0: content len (pld) =146966
2014-10-15 16:21:34 Local3.Debug 192.168.1.130 M0: response code =200
2014-10-15 16:21:35 Local3.Debug 192.168.1.130 M1: fu:0:5e86, 1a08 0001
2014-10-15 16:21:35 Local3.Debug 192.168.1.130 M2: fu:0:d8f1, 1a08 0001
2014-10-15 16:21:35 Local3.Debug 192.168.1.130 M3: fu:0:62b1, 1a08 0001
2014-10-15 16:21:35 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Successful resync http://xxxx.xml
2014-10-15 16:21:35 Local0.Info 192.168.1.130 M0: SPA8000 00:1c:10:5c:47:a0 -- Successful resync http://xxxx.xml
2014-10-15 16:21:35 Local3.Debug 192.168.1.130 M0: FMM >>>> Successful profile
2014-10-15 16:21:35 Local3.Debug 192.168.1.130 M0: fu:1:e360, 0038 0049 043c 0445 1a08 0001
2014-10-15 16:21:35 Local3.Debug 192.168.1.130 M0: system request reboot
2014-10-15 16:21:35 Local3.Debug 192.168.1.130 M3: IDBG: st-0
2014-10-15 16:21:36 Local3.Debug 192.168.1.130 M3: fs:008700:008721:131072
2014-10-15 16:21:36 Local3.Debug 192.168.1.130 M3: fls:af:1:0:0
2014-10-15 16:21:36 Local3.Debug 192.168.1.130 M3: fbr:0:3000:3000:062a2:0008:0007:6.1.12
2014-10-15 16:21:36 Local3.Debug 192.168.1.130 M3: PLKUP: 8192, 2102, 12, 1.5
2014-10-15 16:21:36 Local3.Debug 192.168.1.130 M3: mslink client task (3)  gw=192.168.2.1  ip=192.168.2.4
2014-10-15 16:21:36 Local3.Debug 192.168.1.130 M3: fu:0:62c3, 0003 0001
2014-10-15 16:21:36 Local3.Debug 192.168.1.130 M0: mslink module 3 restart detected
2014-10-15 16:21:38 Local3.Debug 192.168.1.130 M3: system request reboot
2014-10-15 16:21:38 Local3.Debug 192.168.1.130 M1: system request reboot
2014-10-15 16:21:38 Local3.Debug 192.168.1.130 M2: system request reboot
2014-10-15 16:21:38 Local3.Debug 192.168.1.130 M0: fu:1:e416, 03e4 05b0 0001

 

Update: I have solved the reboot loop. Some of the configuration parameters from the default config file generated by SPC were overriding settings populated by DHCP when the ATA booted. This caused a reboot upon each successive profile update.

I found this by setting the update interval to be low (1 minute) and removing half of the configuration profile at a time, then observing the profile update. I added in section by section until the reboot loop resumed, then found the offending setting in that section. 

It would be great if these devices generated a log entry that specified which configuration setting caused a reboot after a profile update.