SPA232D problems integrating with POTS via FXO

[greenturtle99]

I just purchased a SPA232D and I am having problems with the initial configuration with the POTS via the FXO line.  I have dropped back to the factory configuration to partition the problem.  If I connect a phone to the existing working landline and turn the SP232D off, all of the telephone funtion works, incoming and outgoing plus the central server voicemail via the PSTN.

If I power up the SPA232D with the FXO line connected to the POTS RJ11 and the telephone to the FXS line, I can make outgoing calls via the PSTN.  However incoming calls from the PSTN do not ring and the voicemail service never falls back to the central POTS but disconnects.  I can sometimes see the off hook in the status page.

In the powered state, if I register one DECT handset and set it to PSTN and make the Default PSTN, I make an incoming call via the PSTN, it rings and I get a successful connection.  I can also make outgoing calls via the PSTN.  However, if I do not answer the call the voicemail service never falls back to the central POTS but disconnects.

Clearly I don't have good localization of the SPA232D with the POTS (US, Verizon (formerly Frontier), believed to be an old POTS switch, Not a great line, Upstate NY).  But since it works when it is turned off, the basics seem to be there.

Does anyone have advice.  I cannot find any close match on web resources.

Thanks,

Dan Davis

[Dan Lukes]

However incoming calls from the PSTN do not ring

Either:

1. Incomming ring from POTS is not recognized by SPA232D
2. call from FXO is not routed to FXS
3. Outgoing ring to FXS is not recognized by telephone connected to such port

Use syslog&debug log to identify the true reason:

Debug and syslog Messages from SPA1x2 and SPA232D ATA (Analog Telephone Adapters)

Using the slogsrv.exe Utility

if I register one DECT handset ... an incoming call via the PSTN, it rings

Well, it mean that [1] should not be considered favorite reason.

[greenturtle99]

Thanks for reply (I set up the server and started learning the log messages). I now have reliable rings from inbound calls via the FXO.  However, if I do not pick up the line within 2 sometimes 3 rings the call can no longer be answered. From 5 to 7 rings is dropped..  I set the PSTN Ring Thru Delay from 1 to 3 and I get reliable Caller ID now.  However, the behavior of FXO incoming calls remain the same.

I connected up the FXO to my SIP proxy, also my FXS to SIP proxy.  Incoming and outgoing SIP call via the proxy work perfectly.  FXO calls via the SIP proxy exhibit exactly the same behavior.  If I don't get to the call quickly (same number of rings it becomes unanswerable and even the SIP vendor's voicemail cannot pick up the call.

If you have any suggestions regarding the log sections that would give us clues it would be appreciated.  I see another thread with analogous behavior but different device on this forum - message/3205490#3205490 .

[Dan Lukes]

If I understand correctly, you have problem with calls comming in via FXO port not answered within 15s.

You mention the ring, but it's not clear you counted rings on FXO side or FXS side. For example, FXO call may be answered 16s past first ring by default (e.g. about 3 rings) so FXO call become answered and stop ring, but FXS line may continue ringing. About 10s (e.g. 2 rings, e.g. about 5th ring in total) later the PIN entry timeout will fire.

We really need syslog&debug log catched during one problematic call attached here for analysis. We can create countless number of hypothesis without it. It take time and most of will not be related to your true issue. Also, describe the problematic call in detail, event by event (digits dialed, on-hook/of-hook events, rings and tones heared). The good description describe state of BOTH call legs (e.g. for example: FXS is ringing, caller user is hearing ring-back tone OR called user picked phone, calling user hearing busy tone and so on ...) .

As you mentioned no firmware version, I assume you have the latest. 

[greenturtle99]

Understood.

Model:	SPA232D, LAN, FXS, FXO, DECT (1920 - 1930 MHz)
Hardware Version:	1.0.0
Boot Version:	1.2.0 (Jun 12 2012 - 11:08:06)
Firmware Version:	1.3.1 (003_240) Dec 17 2012
Recovery Firmware:	1.2.0 (001)

Apr 24 12:16:13 SPA232D syslog-ng[134]: STATS: dropped 0

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 4,2,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 4,14,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 4,5,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 4,5,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,21,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,10,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,5,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D [17179663.320000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0

Apr 24 12:24:11 SPA232D [17179663.320000]  In cordless Driver Codec 100 and str NSE/8000  chan 0

Apr 24 12:24:11 SPA232D [17179663.320000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0

Apr 24 12:24:11 SPA232D [17179663.324000]  In cordless Driver Codec 255 and str   chan 0

Apr 24 12:24:11 SPA232D [17179663.324000]  In cordless Driver Codec 255 and str   chan 0

Apr 24 12:24:11 SPA232D [17179663.324000]  In cordless Driver Codec 255 and str   chan 0

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,20,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,5,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 5,2,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 5,10,1,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 5,14,1,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 5,5,1,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 5,5,1,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,21,0,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,10,1,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,5,1,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D [17179663.468000]  In cordless Driver Codec 0 and str PCMU/8000  chan 1

Apr 24 12:24:11 SPA232D [17179663.468000]  In cordless Driver Codec 100 and str NSE/8000  chan 1

Apr 24 12:24:11 SPA232D [17179663.468000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 1

Apr 24 12:24:11 SPA232D [17179663.468000]  In cordless Driver Codec 255 and str   chan 1

Apr 24 12:24:11 SPA232D [17179663.468000]  In cordless Driver Codec 255 and str   chan 1

Apr 24 12:24:11 SPA232D [17179663.468000]  In cordless Driver Codec 255 and str   chan 1

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,20,1,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:11 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 12:24:11 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:13 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 12:24:13 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:14 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,18,1,0

Apr 24 12:24:14 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:17 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 12:24:17 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:19 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 12:24:19 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:23 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 12:24:23 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,14,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,12,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,8,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 6,2,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 6,10,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 6,14,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 6,15,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD RTCP Reqt len 12 Data 2,0,7312,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 2

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,22,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,11,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD RTCP Reqt len 12 Data 2,4983808,7312,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 2

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,22,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,11,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,16,0,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,14,1,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,12,1,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:24 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,28,1,0

Apr 24 12:24:24 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:25 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,5,1,0

Apr 24 12:24:25 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:25 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 0,5,1,0

Apr 24 12:24:25 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:25 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,2,1,0

Apr 24 12:24:25 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:25 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,10,1,0

Apr 24 12:24:25 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:25 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,14,1,0

Apr 24 12:24:25 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:25 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,28,1,0

Apr 24 12:24:25 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:25 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,5,1,0

Apr 24 12:24:25 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:34 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:34 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:34 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,15,1,0

Apr 24 12:24:34 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:34 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:34 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:34 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,15,1,0

Apr 24 12:24:34 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:34 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:34 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:35 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,15,1,0

Apr 24 12:24:35 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:35 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,5,1,0

Apr 24 12:24:35 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:35 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:35 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:35 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,14,1,0

Apr 24 12:24:35 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:35 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,12,1,0

Apr 24 12:24:35 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:35 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,28,1,0

Apr 24 12:24:35 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:35 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,5,1,0

Apr 24 12:24:35 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:35 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:35 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:35 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,15,1,0

Apr 24 12:24:35 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:35 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:35 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:36 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,15,1,0

Apr 24 12:24:36 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:36 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:36 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:36 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,15,1,0

Apr 24 12:24:36 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:36 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:36 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:37 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,15,1,0

Apr 24 12:24:37 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:37 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:37 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:37 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,15,1,0

Apr 24 12:24:37 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:37 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:37 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:37 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,9,1,0

Apr 24 12:24:37 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:37 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,16,1,0

Apr 24 12:24:37 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:37 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,14,0,0

Apr 24 12:24:37 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:37 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,12,0,0

Apr 24 12:24:37 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:39 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,5,0,0

Apr 24 12:24:39 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:39 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,5,0,0

Apr 24 12:24:39 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:39 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 7,5,0,0

Apr 24 12:24:39 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:39 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,2,0,0

Apr 24 12:24:39 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:39 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,10,0,0

Apr 24 12:24:39 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:39 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,14,0,0

Apr 24 12:24:39 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:39 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,28,0,0

Apr 24 12:24:39 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:39 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,5,0,0

Apr 24 12:24:39 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:42 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,5,0,0

Apr 24 12:24:42 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:42 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,16,0,0

Apr 24 12:24:42 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:42 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,14,0,0

Apr 24 12:24:42 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:42 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,12,0,0

Apr 24 12:24:42 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:42 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,28,0,0

Apr 24 12:24:42 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

Apr 24 12:24:42 SPA232D msgswitchd[264]:   MSGSWD CH Reqt len 368 Data 8,5,0,0

Apr 24 12:24:42 SPA232D msgswitchd[264]: In netlinkSendMessage contenttype 3

[Dan Lukes]

It's not the log we are looking for. You need to use external syslog server as described here ...

Debug and syslog Messages from SPA1x2 and SPA232D ATA (Analog Telephone Adapters)

Using the slogsrv.exe Utility

... not internal saved log which miss most important messages.

The full log we are searching for look like the one example I attached bellow:

Apr 24 15:10:05 SPA232D msgswitchd[339]:   D2V_SS7_DataReceived
Apr 24 15:10:05 SPA232D msgswitchd[339]:    *** SS7  Message *** 
Apr 24 15:10:05 SPA232D msgswitchd[339]:    *** SS7  Message ***      wInterface [AP_SIP]   wCmd [IAM]0   wsource [AP_DECT]
Apr 24 15:10:05 SPA232D msgswitchd[339]:    IAM Recv    SS7 IAM u32_Instance 0x1000000d    SS7 IAM u32_SessionType 0x0    SS7 IAM u32_Line 2     SS7 IAM u32_Handset 1    SS7 IAM pu8_Src [0][0]
Apr 24 15:10:05 SPA232D msgswitchd[339]: Codec1 = 9, Codec2 = 0 
Apr 24 15:10:05 SPA232D msgswitchd[339]:  printSS7Data done   
cordless_tick(), got something...
cordless_SIP_AP_eventProc(), recevie cmd = 0, src AP = 2
Initial Address
  (instance = 0x1000000d, source = , dest = , line = 2)
Cordless session 0 allocated
cordless_SIP_AP_eventProc(), IAM comes from handset 1
preProcessHandsetOutNum(0x309198)
preProcessHandsetOutNum(2)
cordless_findHsNextCcCall(), hs=0x0x2fb834, mode=1, pLid=0x0x407b7a40, pDid=0x0x407b7a3c
select: DECT line1
found idle call 0 
sysstatus_set_led_status_payton(), led_id: 4, statusCode:15, systemEvent: 0x100106
cordless_SendLineCfm(0, 1, 0)
CC_main(), evt 10
CC_main(), lid=3, cid=0, CC_CALL=0x32a91c
callEventProcTable[0] is cepIdleProc
cepIdleProc(line=0x32a918, call=0x32a91c, event=10(CC_EV_USR_SEIZURE), par=0, par2=0x309198)
cepIdleProc(), lid=3
cepIdleProc(), pname=10.250.250.1
cepIdleProc(), SYS_NOREG_CALL(3)=0, SIP_REGISTER_OK(3)=1
NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_DIALING
NEW_CALL_STATE_CORDLESS(0), CC_CST_IDLE ==> CC_CST_DIALING
cordless_findSessByLineIdandCID(3, 0), session[0].nCcLineId=3, session[0].nCcCallId=0
Start timer for Dialtone timeout,dial_tone_length = 65535
Start timer for Dialtone timeout,dial_tone_length = 65535
cepIdleProc(), sid = 0
Apr 24 15:10:05 SPA232D msgswitchd[339]:    *** SS7  Message *** 
Apr 24 15:10:05 SPA232D msgswitchd[339]:    *** SS7  Message ***      wInterface [AP_DECT]   wCmd [INF]8   wsource [AP_SIP]
Apr 24 15:10:05 SPA232D msgswitchd[339]:  ss7_send_msg : Unknown AP   Send To DECT ss7 -> nl, dst[AP_DECT], dst 2, len 96
Apr 24 15:10:05 SPA232D msgswitchd[339]: In netlinkSendMessage contenttype 1
Set QoS succeed
cordless_eventProc(107(CC_EV_TO_CO_CALL_SEIZURED), 0, 0, 00000000)
Apr 24 15:10:07 SPA232D msgswitchd[339]:   D2V_SS7_DataReceived 
Apr 24 15:10:07 SPA232D msgswitchd[339]:    *** SS7  Message *** 
Apr 24 15:10:07 SPA232D msgswitchd[339]:    *** SS7  Message ***      wInterface [AP_SIP]   wCmd [SAM]1   wsource [AP_DECT]
Apr 24 15:10:07 SPA232D msgswitchd[339]:    SAM Recv    SS7 SAM u32_Instance 0x1000000d    SS7 SAM pu8_Dst 7
Apr 24 15:10:07 SPA232D msgswitchd[339]:  printSS7Data done
cordless_tick(), got something...
cordless_SIP_AP_eventProc(), recevie cmd = 1, src AP = 2
========================
Instance ID = 1000000d
===========================
00
===================================================

===================================================
002fd5ec
===================================================
7
===================================================
Subsequent Address=7 line 3
call state(3, 0) = 1
CC_main(), evt 103
CC_main(), lid=3, cid=0, CC_CALL=0x32a91c
callEventProcTable[1] is cepDialingProc
cepDialingProc(line=0x32a918, call=0x32a91c, event=103(CC_EV_FROM_CO_DSTRING_DIALED), par=0, par2=0x3091ac)
cepDialingProc(), event = 103(CC_EV_FROM_CO_DSTRING_DIALED)
cepDialingProc(), dn = (null)
Apr 24 15:10:07 SPA232D msgswitchd[339]:   D2V_SS7_DataReceived 
Apr 24 15:10:07 SPA232D msgswitchd[339]:    *** SS7  Message *** 
Apr 24 15:10:07 SPA232D msgswitchd[339]:    *** SS7  Message ***      wInterface [AP_SIP]   wCmd [SAM]1   wsource [AP_DECT]
Apr 24 15:10:07 SPA232D msgswitchd[339]:    SAM Recv    SS7 SAM u32_Instance 0x1000000d    SS7 SAM pu8_Dst 7
Apr 24 15:10:07 SPA232D msgswitchd[339]:  printSS7Data done
cordless_tick(), got something...
cordless_SIP_AP_eventProc(), recevie cmd = 1, src AP = 2
========================
Instance ID = 1000000d
===========================
37
===================================================
7
===================================================
002fd5ec
===================================================
7
===================================================
Subsequent Address=7 line 3
call state(3, 0) = 1
CC_main(), evt 103
CC_main(), lid=3, cid=0, CC_CALL=0x32a91c
callEventProcTable[1] is cepDialingProc
cepDialingProc(line=0x32a918, call=0x32a91c, event=103(CC_EV_FROM_CO_DSTRING_DIALED), par=0, par2=0x3091ac)
cepDialingProc(), event = 103(CC_EV_FROM_CO_DSTRING_DIALED)
...
cepDialingProc(), dn = (null)
callEventProcTable[1] is cepDialingProc
cepDialingProc(line=0x32a918, call=0x32a91c, event=34(CC_EV_TMR_DIALPLAN), par=0, par2=(nil))
cepDialingProc(), event = 34(CC_EV_TMR_DIALPLAN)
cepDialingProc(), dn = 800123456
[checkSuppFeatActCode] lid 3 vid 3 dn 800123456
cepDialingProc(CC_EV_TO_CO_CALL_ADDR_COMPLETED(800123456))
cordless_SendDialCfm(0, 1, 2)
gwid: -1, lid: 3
getCodecList line 0x32a918 call 0x32a91c clRemote: 0x0 bInbound 0
pconly: 128
====== Local codec num 1 ======
8
================================
NEW_CALL_STATE(), call 0: old state = CC_CST_DIALING, new state CC_CST_CALLING
NEW_CALL_STATE_CORDLESS(0), CC_CST_DIALING ==> CC_CST_CALLING
cordless_findSessByLineIdandCID(3, 0), session[0].nCcLineId=3, session[0].nCcCallId=0
Calling:800123456@10.250.250.1:5060, rc=0
++++ called number = 800123456
++++ called number = 800123456
RTP_nextMediaPort(), port = 16530
RTP_nextMediaPort(), rc=16528
dbg_tmp: initSdp bOneCodec: 0, sdp->ucNumCodec: 2
AUD_allocCallObj() call(0x3af5d0)
[3:0]AUD ALLOC CALL (port=16528)
+++++ initSession AUD_startRtpRx
[AUD]AUD_startRtpRx(0x3af5d0) lid 3
Local loopback mode: None. Type: None.
Remote loopback mode: None. Type None.
DECT call no rtp channel yet, do nothing
[AUD]RTP Rx Up
cordless_tick(), got something...
cordless_SIP_AP_eventProc(), recevie cmd = 9, src AP = 2
 CHST (instance = 0x1000000d)
============================= 
IID 0x1000000d, CH ID 0, CH state 5
Session current call state = 3
send CC_EV_FROM_CO_CHST
cordless_eventProc(108(CC_EV_TO_CO_CALL_ADDR_COMPLETED), 0, 0, 00000000)
CC_main(), evt 104
++++ receive CC_EV_FROM_CO_CHST 
+++++ CC_main CHST AUD_startRtpRx
[AUD]AUD_startRtpRx(0x3af5d0) lid 3
Local loopback mode: None. Type: None.
Remote loopback mode: None. Type None.
DECT call CoSess id is 0, rtp_chan is 0 
RTP channel setup: udp_no_checksum 0, sysmmetric_rtp 0, tos 0xb8, cos 6, mlb 0.
cordless_start_rtp(), chan:0 remote ip:(null) port:0 local:16528 rx:1 ipt:0 ptime:0
Starting Rx only RTP.
Socket 32 bound to port 16528.
Remote IP/port: 0.0.0.0:0
Codec list from SDP (internal pt):
8
136

Rx payload list:
PCMA/8000(8)
 encaprtp/8000(112)

set RTP_SESSION_OPT_DTMF
RTP configuration:
  audio_mode RTP_MODE_REC_ONLY, media_loop_level RTP_LOOP_LEVEL_NONE, dtmf2833numEndPakcets 3, opts 0x0
  Codec: duration 0, rx_pt_event 101, tx_pt_event -1, tx_pt 0 
         rx[0] 8 PCMA/8000, rx[1] 112 encaprtp/8000, rx[2] -1 
         rx[3] -1 , rx[4] -1 , rx[5] -1 
  Jib: max 180ms, min 60ms, adapt 1
RTP Channel 0 is virgin: 1.
...
NAME "6012"
TOOL Cisco/SPA232D-1.3.1(003_240)
Starting RTCP session on channel 0. Interval 5.
 Bi-directional.
RTCP session started on RTP channel 0.
[AUD]RTCP Up
CC_eventProc(), event: CC_EV_SIG_CALL_PROGRESS(0x3A), lid: 3, par: 59, par2: (nil)
AUD_ccEventProc: event 58 vid 3 par 0x3b par2 0x0
callEventProcTable[3] is cepCallingProc
cepCallingProc(line=0x32a918, call=0x32a91c, event=58(CC_EV_SIG_CALL_PROGRESS), par=59, par2=(nil))
CC:CallProgress
NEW_CALL_STATE(), call 0: old state = CC_CST_CALLING, new state CC_CST_PROCEEDING
NEW_CALL_STATE_CORDLESS(0), CC_CST_CALLING ==> CC_CST_PROCEEDING
cordless_findSessByLineIdandCID(3, 0), session[0].nCcLineId=3, session[0].nCcCallId=0
+++++ SIP_process18x  AUD_startRtpRx
[AUD]AUD_startRtpRx(0x3af5d0) lid 3
Local loopback mode: None. Type: None.
Remote loopback mode: None. Type None.
Already has a RTP channel.
Already has a RTP channel.
cordless_start_rtp(), chan:0 remote ip:(null) port:0 local:16528 rx:1 ipt:0 ptime:0
Going from Tx only to bi-directional.
Remote IP/port: 10.250.250.1:12214
set RTP_SESSION_OPT_DTMF
RTP configuration:
  audio_mode RTP_MODE_ACTIVE, media_loop_level RTP_LOOP_LEVEL_NONE, dtmf2833numEndPakcets 3, opts 0x0
  Codec: duration 20, rx_pt_event 101, tx_pt_event 101, tx_pt 8 
         rx[0] 8 PCMA/8000, rx[1] 112 encaprtp/8000, rx[2] -1 
         rx[3] -1 , rx[4] -1 , rx[5] -1 
  Jib: max 180ms, min 60ms, adapt 1
RTP Channel 0 is virgin: 0.
Just need updating.
RTP session 0 updated
[AUD]RTP Rx Up
SIP_tsInviteClientEventProc(event:27)
SIP_sessTsEventProc(event:27)
xxxx SIP session.c  6e30a00-36057763@10.20.12.247 processInviteResponse statusClass=1
CC_eventProc(), event: CC_EV_SIG_CALL_RINGING(0x38), lid: 3, par: 59, par2: (nil)
AUD_ccEventProc: event 56 vid 3 par 0x3b par2 0x0
callEventProcTable[4] is cepCallingProc
cepCallingProc(line=0x32a918, call=0x32a91c, event=56(CC_EV_SIG_CALL_RINGING), par=59, par2=(nil))
CC:Ringback
NEW_CALL_STATE(), call 0: old state = CC_CST_PROCEEDING, new state CC_CST_PROCEEDING

[greenturtle99]

Thank you.  RTFM.  I hope this is closer.  I did not turn on SIP logging since it seems not to be involved but I did set the voice->system->Miscellaneous Settings (log/debug server IP and debug level 3) per the guide.  Both kernel and system debug is set to report to the log server.  I am slowly getting Wireshark going too but there is quite a learning curve.

--

Dan Davis

Firmware downgrade limit()

cordless_SetHSStatus(020C00D29C)

HsNo[1], HsSubscribe[1], HSStatus[0], HSCalltype[0]

turn on DECT led HS registered

cordless_SetHSStatus(0000000000)

turn on DECT led HS registered

cordless_SetHSStatus(0000000000)

turn on DECT led HS registered

cordless_SetHSStatus(0000000000)

turn on DECT led HS registered

cordless_SetHSStatus(0000000000)

turn on DECT led HS registered

cordless_SetHSStatus(0000000000)

turn on DECT led HS registered

cordless_SetHSStatus(0000000000)

turn on DECT led HS registered

cordless_SetHSStatus(0000000000)

turn on DECT led HS registered

cordless_SetHSStatus(0000000000)

turn on DECT led HS registered

cordless_SetHSStatus(0000000000)

turn on DECT led HS registered

DSRAPP_NOTIFY_LC(2)

DSRAPP_NOTIFY_LC(2)

cordless_GetCallSettings(1)

cordless_GetCallSettings(0, 1, 0, 0, 0, 0, 0, 0)

cordless_GetDefaultOutgoingLine(1)

default outgoing line is 3

dbg_tmp:   header->cur_userid= 0

dbg_tmp:   header->req_root_grp= 0

httpd_handle_request(), request method = 1

httpd_handle_request(), request path = /admin/voice/

httpd_handle_request(), pswlReq->ubType = 0

Requesting call statistics...

Call statistics updated.

DSRAPP_NOTIFY_DT(2)

CC:Clean Up

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

--- OBJ POOL STAT ---

OP:TIMEOU =  65 (120   52)   OP:SIPCOR =   0 (  1   28)

OP:XMNODE = 1024 (1024  112)   OP:SIPCTS =  96 ( 96  960)

OP:SIPSTS =  64 ( 64 6432)   OP:SIPAUS =  12 ( 18  680)

OP:SIPDLG =  20 ( 20  148)   OP:SIPSES =  22 ( 22 9400)

OP:SIPREG =  21 ( 24  468)   OP:SIPLIN =   0 ( 13  140)

OP:SUBDLG =  13 ( 13 6452)   OP:STUNTS =  16 ( 16   68)

OP:SIPCTI =   1 (  1 7416)  

cordless_main() running...... 1

DSRAPP_NOTIFY_VM(3e, 0)

DSRAPP_NOTIFY_LC(2)

cordless_GetCallSettings(1)

cordless_GetCallSettings(0, 1, 0, 0, 0, 0, 0, 0)

DSRAPP_NOTIFY_LC(2)

uchAppCb(), Event 56 received EP 0 lid 2

CH_ASYNC_EVT_SLC received on EP 0 eventdate 0x102 transition SLC_ON in direction UCH_SLC_VOIP_PATH

uchAppCb(), Event 56 received EP 0 lid 2

CH_ASYNC_EVT_SLC received on EP 0 eventdate 0x101 transition SLC_ON in direction UCH_SLC_FXO_PATH

++++ retry query scaps

++++ retry query scaps

+++ need tftp addr..

+++ need tftp addr..

+++ send scaps discovery query

+++ send scaps discovery query

cordless_main() running...... 1

cordless_main() running...... 1

Apr 24 15:53:28 SPA232D syslog-ng[1137]: syslog-ng version 1.6.12 starting

uchAppCb(), Event 56 received EP 0 lid 2

CH_ASYNC_EVT_SLC received on EP 0 eventdate 0x201 transition SLC_OFF in direction UCH_SLC_FXO_PATH

Ring start

Ring start

FXO[2]:Report:sesssion=0;cst=0

FXO[2]:Report:sesssion=0;cst=0

FXO[2]:Start CNDD

uchAppCb(), Event 53 received EP 0 lid 2

CID received on EP 0

    8012780 34323430 33353131 454e0f07 4f592057 20204b52 20202020 360a0220

    33353730 32393939 5930 00 00 00 00 00

    00 00 00 00 00 00 00 00

    00 00 00 00 00 00 00 00

    00 00 00 00 00 00 00 00

    00 00 00 00 00 00 00 00

-- Caller ID:

--     Name             = NEW YORK      

--     Remote Number    = 6075399920

--     Dialable Number  = (null)

--     No Number Reason = (null)

--     No Name Reason   = (null)

--     Message Waiting  = (null)

--     Date and Time    = 04/24 11:53

FXO:CNDD name=NEW YORK       , number=6075399920

+++ async event 14

+++ async event 14

FXO_eventProc(FXO_EV_CNDD(14),2,0x0,0x0)

FXO:CNDD Name=NEW YORK        Phone=6075399920; rt=0

FXO[2]:Report:sesssion=0;cst=0

FXO[2]:Report:sesssion=0;cst=0

callEventProcTable[0] is cepIdleProc

cepIdleProc(line=0x328c60, call=0x328c64, event=89(CC_EV_FXO_UPDATE_CID), par=0, par2=0x40837970)

cepIdleProc(), lid=2

+++ CC_ringThru to fxs

[AUD]Get UCH node for AUD_LINE 2 1.

uchAllocateNode(), Node 0 allocated

[AUD]UCH node 0 allocated to AUD_LINE 2.

uchEnableNode(), Node 0 enbaled

gwid: -1, lid: 2

getCodecList line 0x328c60 call 0x328c64 clRemote: 0x0 bInbound 0

pconly: 0

====== Local codec num 4 ======

0130818================================

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_CALLING

line:2 start ring thru after delay 60

line:2 start ring thru after delay 60

Calling:70@127.0.0.1:5070, rc=1

++++ called number = 70

++++ called number = 70

RTP_nextMediaPort(), port = 16476

RTP_nextMediaPort(), rc=16474

dbg_tmp: initSdp bOneCodec: 0, sdp->ucNumCodec: 3

AUD_allocCallObj() call(0x3964b8)

[2:0]AUD ALLOC CALL (port=16474)

+++++ initSession AUD_startRtpRx

[AUD]AUD_startRtpRx(0x3964b8) lid 2

Local loopback mode: None. Type: None.

Remote loopback mode: None. Type None.

UCH sync parameter hold off time is 0

>>>> uchGetChanAndEP()

<<<< uchGetChanAndEP(0,2)

RTP channel setup: udp_no_checksum 0, sysmmetric_rtp 0, tos 0xb8, cos 6, mlb 0.

uchConnectEpToNode(), connecting EP VoIP 0 to node 0

cordless_start_rtp(), chan:0 remote ip:(null) port:0 local:16474 rx:1 ipt:0 ptime:0

Starting Rx only RTP.

Socket 31 bound to port 16474.

Remote IP/port: 0.0.0.0:0

Codec list from SDP (internal pt):Codec list from SDP (internal pt): 0 0 134 134 136 136

Rx payload list: Rx payload list:  PCMU/8000(0) PCMU/8000(0) NSE/8000(100) NSE/8000(100) encaprtp/8000(112) encaprtp/8000(112)

set RTP_SESSION_OPT_DTMF

uchEnableEchoCan(), lid 2 EP 0 enable

RTP configuration:

  audio_mode RTP_MODE_REC_ONLY, media_loop_level RTP_LOOP_LEVEL_NONE, dtmf2833numEndPakcets 3, opts 0x0

  Codec: duration 0, rx_pt_event 101, tx_pt_event -1, tx_pt 0

         rx[0] 0 PCMU/8000, rx[1] 100 NSE/8000, rx[2] 112 encaprtp/8000

         rx[3] -1 , rx[4] -1 , rx[5] -1

  Jib: max 70ms, min 60ms, adapt 1

RTP Channel 0 is virgin: 1.

#### rtp seq number is 5438

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 39,2,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 39,14,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 39,5,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 39,5,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,21,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,10,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,5,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

RTP session 0 started

uchSetDTMFMute(), DISABLE

[AUD]RTP Rx Up

CC_eventProc(), event: CC_EV_SIG_CALL_ARRIVED(0x34), lid: 0, par: 0, par2: 0x4083774c

AUD_ccEventProc: event 52 vid 0 par 0x0 par2 0x4083774c

getCodecList line 0x323e80 call 0x323e84 clRemote: 0x408377dc bInbound 1

pconly: 0

force to use just G711u for internal voip call

====== Local codec num 1 ======

0================================

uchEnableDspFlow(), Enable DSP flow on EP 1

[AUD]Get UCH node for AUD_LINE 0 0.

uchAllocateNode(), Node 1 allocated

[AUD]UCH node 1 allocated to AUD_LINE 0.

uchConnectEpToNode(), connecting EP FXS to node 1

uchEnableNode(), Node 1 enbaled

CC_eventProc(), inf.strName = NEW YORK

CC_eventProc(), inf.strPhone = 6075399920

callEventProcTable[0] is cepIdleProc

cepIdleProc(line=0x323e80, call=0x323e84, event=19(CC_EV_USR_ACCEPTCALL), par=0, par2=0x4083774c)

cepIdleProc(), lid=0

cepIdleProc(), line->sigProc(CC_CMD_ACCEPT)

cepIdleProc(), call->cinf.bAutoAnswer = 0

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_RINGING

CC_eventProc(), msg CC_EV_USR_ACCEPTCALL(112, 0) sent to CC

[0]CID:CID_initGen() >>> offhook 0 delay 2200 phone 6075399920 name NEW YORK

SLIC_startRing state 0 ts 0x35fba0on 2000 off 4000 len 60000

[0]Ring cad event 0 pol 0

SIP_tsInviteClientEventProc(event:27)

SIP_tsInviteClientEventProc(event:27)

SIP_sessTsEventProc(event:27)

SIP_sessTsEventProc(event:27)

xxxx SIP session.c  a95eda04-77e46b4e@192.168.1.60 processInviteResponse statusClass=1

RTP_nextMediaPort(), port = 16478

RTP_nextMediaPort(), rc=16476

dbg_tmp: SYS_CODEC_NEG_DEFAULT(pLine->ucId=0)): 1

dbg_tmp: initSdp bOneCodec: 1, sdp->ucNumCodec: 3

AUD_allocCallObj() call(0x363300)

[0:0]AUD ALLOC CALL (port=16476)

+++++ SIP_lineCcCmdProc CC_CMD_ACCEPT AUD_startRtpRx

[AUD]AUD_startRtpRx(0x363300) lid 0

Local loopback mode: None. Type: None.

Remote loopback mode: None. Type None.

UCH sync parameter hold off time is 70

>>>> uchGetChanAndEP()

<<<< uchGetChanAndEP(1,3)

RTP channel setup: udp_no_checksum 0, sysmmetric_rtp 0, tos 0xb8, cos 6, mlb 0.

uchConnectEpToNode(), connecting EP VoIP 1 to node 1

cordless_start_rtp(), chan:1 remote ip:(null) port:0 local:16476 rx:1 ipt:0 ptime:0

Starting Rx only RTP.

Socket 36 bound to port 16476.

Remote IP/port: 0.0.0.0:0

Codec list from SDP (internal pt):Codec list from SDP (internal pt): 0 0 134 134 136 136

Rx payload list: Rx payload list:  PCMU/8000(0) PCMU/8000(0) NSE/8000(100) NSE/8000(100) encaprtp/8000(112) encaprtp/8000(112)

set RTP_SESSION_OPT_DTMF

uchEnableEchoCan(), lid 0 EP 1 enable

RTP configuration:

  audio_mode RTP_MODE_REC_ONLY, media_loop_level RTP_LOOP_LEVEL_NONE, dtmf2833numEndPakcets 3, opts 0x0

  Codec: duration 0, rx_pt_event 101, tx_pt_event 101, tx_pt 0

         rx[0] 0 PCMU/8000, rx[1] 100 NSE/8000, rx[2] 112 encaprtp/8000

         rx[3] -1 , rx[4] -1 , rx[5] -1

  Jib: max 180ms, min 60ms, adapt 1

RTP Channel 1 is virgin: 1.

#### rtp seq number is 2501

RTP session 1 started

uchSetDTMFMute(), DISABLE

[AUD]RTP Rx Up

SIP_tsInviteClientEventProc(event:27)

SIP_tsInviteClientEventProc(event:27)

SIP_sessTsEventProc(event:27)

SIP_sessTsEventProc(event:27)

xxxx SIP session.c  a95eda04-77e46b4e@192.168.1.60 processInviteResponse statusClass=1

CC_eventProc(), event: CC_EV_SIG_CALL_RINGING(0x38), lid: 2, par: 45, par2: 0x40837724

AUD_ccEventProc: event 56 vid 2 par 0x2d par2 0x40837724

callEventProcTable[3] is cepCallingProc

cepCallingProc(line=0x328c60, call=0x328c64, event=56(CC_EV_SIG_CALL_RINGING), par=45, par2=0x40837724)

CC:Ringback

NEW_CALL_STATE(), call 0: old state = CC_CST_CALLING, new state CC_CST_PROCEEDING

+++++ cepCallingProc   AUD_stopRtpRx

[AUD]AUD_stopRtpRx(0x3964b8)

cordless_stop_rtp_rx(), Channel 0.

RTP channel 0 going from Rx to Idle.

RTP configuration:

  audio_mode RTP_MODE_INACTIVE, media_loop_level RTP_LOOP_LEVEL_NONE, dtmf2833numEndPakcets 3, opts 0x0

  Codec: duration 0, rx_pt_event 101, tx_pt_event -1, tx_pt 0

         rx[0] 0 PCMU/8000, rx[1] 100 NSE/8000, rx[2] 112 encaprtp/8000

         rx[3] -1 , rx[4] -1 , rx[5] -1

  Jib: max 70ms, min 60ms, adapt 1

Apr 24 15:53:45 SPA232D [17180566.720000]  In cordless Driver Codec 0 and str PCMU/8000  chan 0

Apr 24 15:53:45 SPA232D [17180566.720000]  In cordless Driver Codec 100 and str NSE/8000  chan 0

Apr 24 15:53:45 SPA232D [17180566.724000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 0

Apr 24 15:53:45 SPA232D [17180566.724000]  In cordless Driver Codec 255 and str   chan 0

Apr 24 15:53:45 SPA232D [17180566.724000]  In cordless Driver Codec 255 and str   chan 0

Apr 24 15:53:45 SPA232D [17180566.724000]  In cordless Driver Codec 255 and str   chan 0

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,20,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,5,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 40,2,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 40,10,1,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 40,14,1,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 40,5,1,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 40,5,1,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,21,0,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,10,1,0

RTP channel 0 is now Idle.

[AUD]RTP Down

uchPlayPstnTone(), Play PSTN Tone 9 lid 2 EP 0

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

uchAppCb(), Event 56 received EP 0 lid 2

CH_ASYNC_EVT_SLC received on EP 0 eventdate 0x202 transition SLC_OFF in direction UCH_SLC_VOIP_PATH

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,5,1,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,20,1,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:45 SPA232D [17180566.896000]  In cordless Driver Codec 0 and str PCMU/8000  chan 1

Apr 24 15:53:45 SPA232D [17180566.896000]  In cordless Driver Codec 100 and str NSE/8000  chan 1

Apr 24 15:53:45 SPA232D [17180566.896000]  In cordless Driver Codec 112 and str encaprtp/8000  chan 1

Apr 24 15:53:45 SPA232D [17180566.896000]  In cordless Driver Codec 255 and str   chan 1

Apr 24 15:53:45 SPA232D [17180566.896000]  In cordless Driver Codec 255 and str   chan 1

Apr 24 15:53:45 SPA232D [17180566.896000]  In cordless Driver Codec 255 and str   chan 1

Apr 24 15:53:45 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 15:53:45 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Set QoS succeed

Set QoS succeed

[0]Ring cad event 1 pol 0

uchCadEventProc(), event 1 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:53:47 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:53:47 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

CID:OnHookTx Pol

[0]CID CID_ST_POLREV_POST_DELAY

uchDisplayCIDFSK(), EP 1 lid 0 buflen 95 overhead 60 SZ_MAX_USERDATA 200

uchDisplayCIDFSK(), FSK Caller ID standard is 0(bell 202)

uchDisplayCIDFSK(), SeizeFreq 0x16 MarkFreq 0xc

[0]CID Start DTMF/FSK, CID_ST_ACTIVE

Apr 24 15:53:48 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,18,1,0

Apr 24 15:53:48 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchAppCb(), Event 58 received EP 1 lid 0

receive CH_ASYNC_CIT_TRANSMITTED

[0]CID CID:DONE

[0]CID CID_ST_ACTIVE_POST_DELAY

[0]CID CID_ST_IDLE

[0]Ring cad event 0 pol 0

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:53:51 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 15:53:51 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

++++ retry query scaps

++++ retry query scaps

+++ need tftp addr..

+++ need tftp addr..

+++ send scaps discovery query

+++ send scaps discovery query

[0]Ring cad event 1 pol 0

uchCadEventProc(), event 1 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:53:53 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:53:53 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

[0]Ring cad event 0 pol 0

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:53:57 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 15:53:57 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

+++ Cancel ring thru

+++ CC_cancel_ringthru_fxs cid 0

callEventProcTable[4] is cepCallingProc

cepCallingProc(line=0x328c60, call=0x328c64, event=11(CC_EV_USR_ENDCALL), par=0, par2=(nil))

NEW_CALL_STATE(), call 0: old state = CC_CST_PROCEEDING, new state CC_CST_IDLE

[AUD]Release UCH node for AUD_LINE 2.

uchDisableNode(), Node 0 released

[AUD]UCH node 0 freed.

connect FXO EP 0 to node -1

uchConnectEpToNode(), invalid node ID: -1

uchConnectEpToNode(), invalid node ID: -1

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,14,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,12,0,0

0: is OffHook: 1

sysstatus_set_led_status_payton(), led_id: 3, statusCode:11, systemEvent: 0x100101

FXO:Off Hook

FXO[2]:Stop CNDD

FXO[2]:Report:sesssion=413ebe30;cst=0

FXO[2]:Report:sesssion=413ebe30;cst=0

CC_eventProc(), event: CC_EV_USR_OFFHOOK(0x2), lid: 2, par: 0, par2: (nil)

AUD_ccEventProc: event 2 vid 2 par 0x0 par2 0x0

callEventProcTable[0] is cepIdleProc

cepIdleProc(line=0x328c60, call=0x328c64, event=10(CC_EV_USR_SEIZURE), par=0, par2=(nil))

cepIdleProc(), lid=2

cepIdleProc(), pname=192.168.1.20

cepIdleProc(), SYS_NOREG_CALL(2)=16, SIP_REGISTER_OK(2)=1

[AUD]Get UCH node for AUD_LINE 2 0.

uchAllocateNode(), Node 0 allocated

[AUD]UCH node 0 allocated to AUD_LINE 2.

uchConnectEpToNode(), connecting EP FXO to node 0

uchEnableNode(), Node 0 enbaled

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_DIALING

uchPlayPstnTone(), Play PSTN Tone 1 lid 2 EP 0

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

SIP_releaseAudioResources() entered ################!!!!!!!!!!!!!!!!!

Requesting call statistics...

RTP TX stats updated for channel 0

RTP RX stats updated for channel 0

RTP TX stats updated for channel 1

RTP RX stats updated for channel 1

Call statistics updated.

AUD_releaseCallObj() call(0x3964b8)

[AUD]AUD_stopRtpTx(0x3964b8)

cordless_stop_rtp_tx(), Channel 0.

*** RTP channel not in Tx. Nothing to stop!

*** RTP channel not in Tx. Nothing to stop!

[AUD]RTP Down

[AUD]AUD_stopRtpRx(0x3964b8)

cordless_stop_rtp_rx(), Channel 0.

*** RTP channel not in Rx. Nothing to stop!

*** RTP channel not in Rx. Nothing to stop!

[AUD]RTP Down

[AUD]AUD_releaseRtp(0x3964b8)

cordless_stop_rtp(), releasing RTP channel:0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,8,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 41,2,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

##### RTP_SEQ_NUM_EVT 5438

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 41,10,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 41,14,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 41,15,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD RTCP Reqt len 12 Data 2,0,7312,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 2

cordless_stop_rtp(), RTP session 0 stopped succussfully

uchRelChanAndEP(0, 2)

uchDisconnectEpFromNode(), disconnecting EP VoIP 0 from node 0

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,22,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,11,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

[AUD]RTP channel released

[2:0]AUD Rel Call

SIP_releaseAudioResources(), CC_lineIsIdle(2)=0, gAudLine[2].bIvr=0, AUD_relUchNode????????????

SIP_releaseAudioResources() exit   ################!!!!!!!!!!!!!!!!!

SIP_tsInviteClientEventProc(event:52)

SIP_tsInviteClientEventProc(event:52)

SIP_sessTsEventProc(event:26)

SIP_sessTsEventProc(event:26)

SIP_sessDlgEventProc: event: 44(SIP_EV_DLG_BYED), ucState: 0

SIP_releaseAudioResources() entered ################!!!!!!!!!!!!!!!!!

Requesting call statistics...

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD RTCP Reqt len 12 Data 2,4983808,7312,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 2

RTP TX stats updated for channel 1

RTP RX stats updated for channel 1

Call statistics updated.

AUD_releaseCallObj() call(0x363300)

[AUD]AUD_stopRtpTx(0x363300)

cordless_stop_rtp_tx(), Channel 1.

*** RTP channel not in Tx. Nothing to stop!

*** RTP channel not in Tx. Nothing to stop!

[AUD]RTP Tx Down

[AUD]AUD_stopRtpRx(0x363300)

cordless_stop_rtp_rx(), Channel 1.

RTP channel 1 going from Rx to Idle.

RTP configuration:

  audio_mode RTP_MODE_INACTIVE, media_loop_level RTP_LOOP_LEVEL_NONE, dtmf2833numEndPakcets 3, opts 0x0

  Codec: duration 0, rx_pt_event 101, tx_pt_event 101, tx_pt 0

         rx[0] 0 PCMU/8000, rx[1] 100 NSE/8000, rx[2] 112 encaprtp/8000

         rx[3] -1 , rx[4] -1 , rx[5] -1

  Jib: max 180ms, min 60ms, adapt 1

RTP channel 1 is now Idle.

[AUD]RTP Down

[AUD]AUD_releaseRtp(0x363300)

cordless_stop_rtp(), releasing RTP channel:1

##### RTP_SEQ_NUM_EVT 2501

cordless_stop_rtp(), RTP session 1 stopped succussfully

uchRelChanAndEP(1, 3)

uchDisconnectEpFromNode(), disconnecting EP VoIP 1 from node 1

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,22,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,11,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

[AUD]RTP channel released

[0:0]AUD Rel Call

SIP_releaseAudioResources(), CC_lineIsIdle(0)=0, gAudLine[0].bIvr=0, AUD_relUchNode????????????

SIP_releaseAudioResources() exit   ################!!!!!!!!!!!!!!!!!

CC_eventProc(), event: CC_EV_SIG_CALL_ENDED(0x37), lid: 0, par: 13, par2: (nil)

AUD_ccEventProc: event 55 vid 0 par 0xd par2 0x0

callEventProcTable[5] is cepRingingProc

cepRingingProc(line=0x323e80, call=0x323e84, event=55(CC_EV_SIG_CALL_ENDED), par=13, par2=(nil))

CC:Ended

CC_callEventProc(), network signal call ended, bDECTCalltransfer=0

NEW_CALL_STATE(), call 0: old state = CC_CST_RINGING, new state CC_CST_IDLE

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,0,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

[AUD]Release UCH node for AUD_LINE 0.

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,14,1,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchDisableNode(), Node 1 released

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,12,1,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

[AUD]UCH node 1 freed.

SLIC_stopRing

[0]Ring cad event 2 pol 0

uch_setDFC_BGM(), EP 1 value 0x65f4 ret 0

SLIC_stopTone

SIP_tsInviteClientEventProc(event:27)

SIP_tsInviteClientEventProc(event:27)

SIP_sessTsEventProc(event:27)

SIP_sessTsEventProc(event:27)

DLG Terminated 4fb830

SIP_sessDlgEventProc: event: 40(SIP_EV_DLG_TERM), ucState: 4

Apr 24 15:53:58 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,28,1,0

Apr 24 15:53:58 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

DLG Terminated 4fb8c4

SIP_sessDlgEventProc: event: 40(SIP_EV_DLG_TERM), ucState: 4

Sess Terminated

SIP_releaseAudioResources() entered ################!!!!!!!!!!!!!!!!!

SIP_releaseAudioResources(), CC_lineIsIdle(0)=1, gAudLine[0].bIvr=0, AUD_relUchNode????????????

[AUD]Release UCH node for AUD_LINE 0.

AUD_LINE 0 has no associated UCH node.

SIP_releaseAudioResources() exit   ################!!!!!!!!!!!!!!!!!

SIP_tsInviteClientEventProc(event:2)

SIP_tsInviteClientEventProc(event:2)

SIP_sessTsEventProc(event:31)

SIP_sessTsEventProc(event:31)

Sess Terminated

SIP_releaseAudioResources() entered ################!!!!!!!!!!!!!!!!!

SIP_releaseAudioResources(), CC_lineIsIdle(2)=0, gAudLine[2].bIvr=0, AUD_relUchNode????????????

SIP_releaseAudioResources() exit   ################!!!!!!!!!!!!!!!!!

cordless_main() running...... 1

Set QoS succeed

Set QoS succeed

Set QoS succeed

uchCadEventProc(), event 3 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:08 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:54:08 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

+++ async event 4

+++ async event 4

FXO_eventProc(FXO_EV_TONE_TIMEOUT(4),2,0x0,0x35e430)

CC_eventProc(), event: CC_EV_USR_TONEEND(0x7), lid: 2, par: 0, par2: 0x35e430

AUD_ccEventProc: event 7 vid 2 par 0x0 par2 0x35e430

callEventProcTable[1] is cepDialingProc

cepDialingProc(line=0x328c60, call=0x328c64, event=7(CC_EV_USR_TONEEND), par=0, par2=0x35e430)

cepDialingProc(), event = 7(CC_EV_USR_TONEEND)

NEW_CALL_STATE(), call 0: old state = CC_CST_DIALING, new state CC_CST_INVALID

callEventProcTable[0] is cepIdleProc

cepIdleProc(line=0x328c60, call=0x328f2c, event=7(CC_EV_USR_TONEEND), par=0, par2=0x35e430)

cepIdleProc(), lid=2

uchPlayPstnTone(), Play PSTN Tone 8 lid 2 EP 0

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:08 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 15:54:08 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

+++ async event 10

+++ async event 10

FXO_eventProc(FXO_EV_VOIP_DIALTONE_TIMEOUT(10),2,0x0,0x0)

++ voip failed; state = 3

+++ invalid pstn-2-voip call

uchCadEventProc(), event 1 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:08 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:54:08 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:08 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 15:54:08 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 1 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:09 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:54:09 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:09 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 15:54:09 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 1 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:09 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:54:09 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:09 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 15:54:09 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 1 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:10 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:54:10 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:10 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 15:54:10 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 1 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:10 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:54:10 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:10 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 15:54:10 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 1 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:11 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:54:11 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchCadEventProc(), event 0 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:11 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,15,1,0

Apr 24 15:54:11 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

uchAppCb(), Event 54 received EP 0 lid 2

uchAppCb(), send FXO_EV_PSTN_DISCONNECT_TONE to fxo EP 0 lid 2

+++ async event 13

+++ async event 13

FXO_eventProc(FXO_EV_PSTN_DISCONNECT_TONE(13),2,0x0,0x0)

FXO:PSTN Disconnect Tone

uchStopPstnTone(), Stop PSTN Tone lid 2 EP 0

uchCadEventProc(), event 2 on EP 0 ts->comp 2 cbEND 0x0x7d6f4

Apr 24 15:54:11 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

Apr 24 15:54:11 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:54:11 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,9,1,0

Apr 24 15:54:11 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

sysstatus_set_led_status_payton(), led_id: 3, statusCode:3, systemEvent: 0x100099

FXO[2]:On Hook

FXO[2]:On Hook

CC_eventProc(), event: CC_EV_USR_ONHOOK(0x1), lid: 2, par: 0, par2: (nil)

AUD_ccEventProc: event 1 vid 2 par 0x0 par2 0x0

callEventProcTable[0] is cepIdleProc

cepIdleProc(line=0x323e80, call=0x324414, event=11(CC_EV_USR_ENDCALL), par=0, par2=(nil))

cepIdleProc(), lid=0

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

callEventProcTable[6] is cepInvalidProc

cepInvalidProc(line=0x328c60, call=0x328c64, event=11(CC_EV_USR_ENDCALL), par=0, par2=(nil))

NEW_CALL_STATE(), call 0: old state = CC_CST_INVALID, new state CC_CST_IDLE

[AUD]Release UCH node for AUD_LINE 2.

uchDisableNode(), Node 0 released

[AUD]UCH node 0 freed.

uchStopPstnTone(), Stop PSTN Tone lid 2 EP 0

FXO[2]:Stop CNDD

Apr 24 15:54:11 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,16,1,0

FXO[2]:Report:sesssion=0;cst=0

FXO[2]:Report:sesssion=0;cst=0

Apr 24 15:54:11 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:54:11 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,14,0,0

Apr 24 15:54:11 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Apr 24 15:54:11 SPA232D msgswitchd[268]:   MSGSWD CH Reqt len 368 Data 0,12,0,0

Apr 24 15:54:11 SPA232D msgswitchd[268]: In netlinkSendMessage contenttype 3

Set QoS succeed

cordless_main() running...... 1

uchAppCb(), Event 56 received EP 0 lid 2

CH_ASYNC_EVT_SLC received on EP 0 eventdate 0x102 transition SLC_ON in direction UCH_SLC_VOIP_PATH

uchAppCb(), Event 56 received EP 0 lid 2

CH_ASYNC_EVT_SLC received on EP 0 eventdate 0x101 transition SLC_ON in direction UCH_SLC_FXO_PATH

CC:Clean Up

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 1: old state = CC_CST_IDLE, new state CC_CST_IDLE

NEW_CALL_STATE(), call 2: old state = CC_CST_IDLE, new state CC_CST_IDLE

--- OBJ POOL STAT ---

OP:TIMEOU =  62 (120   52)   OP:SIPCOR =   0 (  1   28)

OP:XMNODE = 1024 (1024  112)   OP:SIPCTS =  96 ( 96  960)

OP:SIPSTS =  61 ( 64 6432)   OP:SIPAUS =  12 ( 18  680)

OP:SIPDLG =  20 ( 20  148)   OP:SIPSES =  22 ( 22 9400)

OP:SIPREG =  21 ( 24  468)   OP:SIPLIN =   0 ( 13  140)

OP:SUBDLG =  13 ( 13 6452)   OP:STUNTS =  16 ( 16   68)

OP:SIPCTI =   1 (  1 7416)  

++++ retry query scaps

++++ retry query scaps

+++ need tftp addr..

+++ need tftp addr..

+++ send scaps discovery query

+++ send scaps discovery query

cordless_main() running...... 1

cordless_main() running...... 1

cordless_main() running...... 1

++++ retry query scaps

++++ retry query scaps

+++ need tftp addr..

+++ need tftp addr..

+++ send scaps discovery query

+++ send scaps discovery query

cordless_main() running...... 1

[Dan Lukes]

Well, I will mark the important lines:

Apr 24 15:53:28
Ring start
CID received on EP 0-- Caller ID:
--     Name             = NEW YORK       
--     Remote Number    = 6075399920
+++ CC_ringThru to fxs

Ring detected on FXO line, caller-ID received succesfully, going to ring-thru to fxs.

NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_RINGING
[0]CID:CID_initGen() >>> offhook 0 delay 2200 phone 6075399920 name NEW YORK
NEW_CALL_STATE(), call 0: old state = CC_CST_CALLING, new state CC_CST_PROCEEDING
Apr 24 15:53:45
[0]Ring cad event 1 pol 0
uchDisplayCIDFSK(), FSK Caller ID standard is 0(bell 202)
[0]CID CID:DONE
[0]CID CID_ST_ACTIVE_POST_DELAY
[0]CID CID_ST_IDLE
[0]Ring cad event 0 pol 0
Apr 24 15:53:51
[0]Ring cad event 1 pol 0
[0]Ring cad event 0 pol 0
Apr 24 15:53:57
+++ Cancel ring thru
+++ CC_cancel_ringthru_fxs cid 0 
Apr 24 15:53:58
0: is OffHook: 1
FXO:Off Hook

Call going to FXS, first ring, Caller ID transmitted using Bell 202 protocol. Second ring.

Ringthru cancelled, FXO line off-hook (e.g. call is answered).

I assume that 16second of PSTN Answer Delay passed, so call has been auto-answered.

Unfortunatelly, you didn't described the state of call legs (what is hearing the caller after the third ring-back ?).

You should also look at Line 1 Status: Call 1 State and Call 1 Type before third ring and again past third ring.

cepCallingProc(line=0x328c60, call=0x328c64, event=11(CC_EV_USR_ENDCALL), par=0, par2=(nil))
NEW_CALL_STATE(), call 0: old state = CC_CST_PROCEEDING, new state CC_CST_IDLE
connect FXO EP 0 to node -1
Apr 24 15:53:58
NEW_CALL_STATE(), call 0: old state = CC_CST_IDLE, new state CC_CST_DIALING
NEW_CALL_STATE(), call 0: old state = CC_CST_RINGING, new state CC_CST_IDLE
Apr 24 15:53:58
[0]Ring cad event 2 pol 0
uchPlayPstnTone(), Play PSTN Tone 8 lid 2 EP 0
FXO_eventProc(FXO_EV_VOIP_DIALTONE_TIMEOUT(10),2,0x0,0x0)
++ voip failed; state = 3
+++ invalid pstn-2-voip call
Apr 24 15:54:11
uchAppCb(), send FXO_EV_PSTN_DISCONNECT_TONE to fxo EP 0 lid 2
FXO[2]:On Hook
CC_eventProc(), event: CC_EV_USR_ONHOOK(0x1), lid: 2, par: 0, par2: (nil)
NEW_CALL_STATE(), call 0: old state = CC_CST_INVALID, new state CC_CST_IDLE

After another 16 seconds (about 3 rings) overall cleanup.

Busy (disconnect) tone sent to FXO, then hang-up (on-hook).

Unfortunatelly, I'm not fammiliar with FXO operations (e.g. what happened with auto-answered call, what timeouts apply to it) as we have no POTS lines (we are using SPA232D as DECT<->SIP bridge only).

[Dan Lukes · Accepted solution]

Just notice found elsewhere - it may, or may not help:

1. You can set to "no". When  disabled, incoming PSTN calls will not be auto-answered by the SPA.
2. "The SPA-3000 is two independent ATAs in one box. For "Line 1 to @gw0"  and "PSTN Line Ring Thru to Line 1" functionality, the SPA-3000 actually  places a VoIP call from one side of the box to the other.

Also, read page 210 of Cisco SPA232D Mobility Enhanced Phone Adapter Administration Guide

It describe the PSTN-to-Line 1 call process a little.

Based on pages 209-210 I wish that:

1. incomming FXO call ring thru on FXS line
2. if not picked in time, then it's autoanswered by ATA. It start to play dial tone. Caller should enter target number via DTMF
3. target number is processed by default dial plan

        If no target number entered until dial-tome timeouted, the call is terminated.

It seems to comply with your observation.

So try to set PSTN-To-VoIP Gateway Enable to No and try again.

[greenturtle99]

You nailed it. It was the PSTN-TO-VoIP Gateway that was picking up the call.  By setting the PSTN-To-VoIP Gateway Enable combo box to "no" the pick up stopped.

However, it appears that the value of the "PSTN Answer Delay" (Page 210) has some effect in when the SPA232D stops listening for the pick up.  The value seems to have a strong effect when the PSTN-TO-VoIP Gateway is disabled permitting roughly the full value of the time.  In testing with the PSTN Answer Delay set at 30 and PSTN Answer Delay set to no, I let the caller ring without picking up Line 1.  The SPA232D stopped permitting pickup, but after a few more rings from the caller the PSTN had reset, started ringing Line 1 again and permitted the call pick up.

If you don't set the PSTN Answer Delay higher than the default 16 you still won't get many rings but, if PSTN-TO-VoIP gateway is set to no, and Line 1 is still ringing --- you can pick up.  I was unable to get good correllations regarding when the leg changes happened.

I think there are a few other values (setting variables) that have an effect since, when I left the PSTN-To-VoIP Gateway Enable to "yes" and the "PSTN Answer Delay" at 30, I was permitted 5-6 rings versus 3-4 previously and 11-12 when set to "no".  This is all repeatable.

Thanks, I believe the core of the question is solved but I will work with the setting variables a bit more to see how they interact.

[Dan Lukes]

You nailed it.

Glad to hear it.

I think there are a few other values (setting variables) that have an effect since, when I left the PSTN-To-VoIP Gateway Enable to "yes" and the "PSTN Answer Delay" at 30, I was permitted 5-6 rings versus 3-4 previously and 11-12 when set to "no".  This is all repeatable.
Thanks, I believe the core of the question is solved but I will work with the setting variables a bit more to see how they interact.

Most common ring cycle is about 5 seconds. E.g. 30 seconds is equivalent of 6 rings, althougth only 5 may be heared (the ring cycle may not be exactly 5 seconds). Four rings are about 20s and 12 rings is about 60 seconds.

The full log may help to recognize what happened during your tests.

I believe the core of the question is solved but I will work with the setting variables a bit more to see how they interact.

OK. Rate helpfull posts if you wish, mark the thread as answered if you feel it's solved.

[Dan Lukes]

Just update - the Cisco SPA232D Mobility Enhanced Phone Adapter Administration Guide document referred above no longer exist. Thus see archive copy of spa232d_ag_78-20305.pdf bellow.

The recent version of Cisco SPA232D Mobility Enhanced Phone Adapter Administration Guide claim somewhat different description of the same things on page 215. I don't know it's just documentation change or the device behavior has changed with recent firmware.

As I assume this version of Administration Guide will gets replaced by other version after a while so I'm attaching current version (admin_guide_spa232d-302d_1_3_5/SPA232D_Admin_guide.pdf dedicated to 1.3.5 firmware version) as well.

[NN HH]

Hello

I'm having this issue too. BUT no one has documents the steps required to fix it.

1. Upon incoming call, via pstn, it will only ring for 15 seconds (6 rings), then the ATA automatically answers!

Why would it answer the call?

Why wouldn't it just drop the call? 

Why is the default behavior to auto-pickup and not route call after 6 rings / 15 seconds???

2. Previously #09 or #90 or #99 (i forget) would switch to outbound channel (FXO) so I could make an outbound call via PSTN. 

HOWEVER NOTHING IS DOCUMENTED ANYWHERE what this is now?

Can someone please help?

Really appreciate it.

Cheers,

Damien.

[Dan Lukes]

This thread has been considered answered so long time ago. If you have similar issue follow the answer already provided. If you have dissimilar issue ask in brand new thread.

I assume you are familiar with SPA232D Administration Guide.