thr3ads.net - asterisk users - [asterisk-users] Timecondition fallthrough on 2nd GSM Modem, First modem and ZAP's are all fine [Aug 2010]

If this information is useful, please help other people find it:
Share via:

Raimund Sacherer

2010-Aug-26 08:41 UTC

[asterisk-users] Timecondition fallthrough on 2nd GSM Modem, First modem and ZAP's are all fine

Hello,

we have an asterisk (1.4.21.2) with ZAP and mISDN channels, the mISDN are 2
incoming GSM Modems, each with 2 simcards.

No, the mISDN line one and two are fine, but when I get a call on line 3
something with the time is wrong. Timeconditions fall through to off-hours even
if the time of the call is clearly inside business hours, here a log excerpt:

[Aug 26 11:04:36] VERBOSE[3112] logger.c: Really destroying SIP dialog
'39992a2d3d4aae816ff4d5f04710e6a1 at 10.10.254.254' Method: OPTIONS
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [2000 at from-pstn:1]
Set("mISDN/3-u10744", "__FROM_DID=2000") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [2000 at from-pstn:2]
GotoIf("mISDN/3-u10744", "0 ?cidok") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [2000 at from-pstn:3]
Set("mISDN/3-u10744", "CALLERID(name)=6019") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [2000 at from-pstn:4]
NoOp("mISDN/3-u10744", "CallerID is "6019"
<6019>") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [2000 at from-pstn:5]
Set("mISDN/3-u10744",
"__CALLINGPRES_SV=allowed_not_screened") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [2000 at from-pstn:6]
SetCallerPres("mISDN/3-u10744", "allowed_not_screened") in
new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [2000 at from-pstn:7]
Goto("mISDN/3-u10744", "timeconditions|4|1") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Goto (timeconditions,4,1)
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [4 at
timeconditions:1] GotoIfTime("mISDN/3-u10744",
"15:00-18:59|mon-thu|*|*?ivr-7|s|1") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [4 at
timeconditions:2] Goto("mISDN/3-u10744",
"timeconditions|5|1") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Goto (timeconditions,5,1)
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [5 at
timeconditions:1] GotoIfTime("mISDN/3-u10744",
"08:01-14:59|fri|*|*?ivr-7|s|1") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [5 at
timeconditions:2] Goto("mISDN/3-u10744", "ivr-5|s|1") in new
stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Goto (ivr-5,s,1)
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [s at ivr-5:1]
Set("mISDN/3-u10744", "LOOPCOUNT=0") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [s at ivr-5:2]
Set("mISDN/3-u10744", "__DIR-CONTEXT=default") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [s at ivr-5:3]
Set("mISDN/3-u10744", "_IVR_CONTEXT_ivr-5=") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [s at ivr-5:4]
Set("mISDN/3-u10744", "_IVR_CONTEXT=ivr-5") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [s at ivr-5:5]
GotoIf("mISDN/3-u10744", "0?begin") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [s at ivr-5:6]
Answer("mISDN/3-u10744", "") in new stack
[Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing [s at ivr-5:7]
Wait("mISDN/3-u10744", "1") in new stack
[Aug 26 11:04:38] VERBOSE[9172] logger.c:     -- Executing [s at ivr-5:8]
Set("mISDN/3-u10744", "TIMEOUT(digit)=3") in new stack
[Aug 26 11:04:38] VERBOSE[9172] logger.c:     -- Digit timeout set to 3
[Aug 26 11:04:38] VERBOSE[9172] logger.c:     -- Executing [s at ivr-5:9]
Set("mISDN/3-u10744", "TIMEOUT(response)=12") in new stack
[Aug 26 11:04:38] VERBOSE[9172] logger.c:     -- Response timeout set to 12
[Aug 26 11:04:38] VERBOSE[9172] logger.c:     -- Executing [s at ivr-5:10]
BackGround("mISDN/3-u10744", "custom/Cerrado_Normal_2") in
new stack


As you can see, it's clearly not off-hours but the line is treated as such,
so I am confused, where does the time come from? Is it not the current computer
system time?

I also checked and the only nominater is mISDN/3 and mISDN/4, served on
different threads etc. Other calls (mISDN/1, mISDN/2, Zaps) all behave fine.

Does anyone have an iDea what's going on? I requested to have the GSM modems
resetted but it seems to have no effect!

for comparision, an mISDN/2 channel:
[Aug 26 09:56:30] VERBOSE[8890] logger.c:     -- Executing [s at ext-did:7]
SetCallerPres("mISDN/2-u10712", "allowed_not_screened") in
new stack
[Aug 26 09:56:30] VERBOSE[8890] logger.c:     -- Executing [s at ext-did:8]
Goto("mISDN/2-u10712", "timeconditions|3|1") in new stack
[Aug 26 09:56:30] VERBOSE[8890] logger.c:     -- Goto (timeconditions,3,1)
[Aug 26 09:56:30] VERBOSE[8890] logger.c:     -- Executing [3 at
timeconditions:1] GotoIfTime("mISDN/2-u10712",
"08:00-12:59|mon-thu|*|*?ivr-7|s|1") in new stack
[Aug 26 09:56:30] VERBOSE[8890] logger.c:     -- Goto (ivr-7,s,1)
[Aug 26 09:56:30] VERBOSE[8890] logger.c:     -- Executing [s at ivr-7:1]
Set("mISDN/2-u10712", "LOOPCOUNT=0") in new stack
[Aug 26 09:56:30] VERBOSE[8890] logger.c:     -- Executing [s at ivr-7:2]
Set("mISDN/2-u10712", "__DIR-CONTEXT=default") in new stack






-
RunSolutions
     Open Source It Consulting
-
Email: rs at runsolutions.com

Parc Bit - Centro Empresarial Son Espanyol
Edificio Estel - Local 3D
07121 -  Palma de Mallorca
Baleares

----- Mensaje original -----> Send asterisk-users mailing list submissions to
> asterisk-users at lists.digium.com
> 
> To subscribe or unsubscribe via the World Wide Web, visit
> http://lists.digium.com/mailman/listinfo/asterisk-users
> or, via email, send a message with subject or body 'help' to
> asterisk-users-request at lists.digium.com
> 
> You can reach the person managing the list at
> asterisk-users-owner at lists.digium.com
> 
> When replying, please edit your Subject line so it is more specific
> than "Re: Contents of asterisk-users digest..."
> 
> Today's Topics:
> 
> 1. Re: Dahdi install gone wrong (A J Stiles)
> 2. AEL - what is error: ael.flex:647 ael_yylex: Unhandled
> char(s): (Zeeshan Zakaria)
> 3. Re: Should I move to 1.6 or 1.8, or stay with 1.4?
> (Zeeshan Zakaria)
> 4. Re: AEL - what is error: ael.flex:647 ael_yylex:Unhandled
> char(s): (Danny Nicholas)
> 5. Re: AEL - what is error: ael.flex:647 ael_yylex:Unhandled
> char(s): (Zeeshan Zakaria)
> 6. Re: AEL - what is error: ael.flex:647 ael_yylex:Unhandled
> char(s): (Watkins, Bradley)
> 7. Re: AEL - what is error: ael.flex:647 ael_yylex:Unhandled
> char(s): (Zeeshan Zakaria)
> 8. Re: Should I move to 1.6 or 1.8, or stay with 1.4? (Leif Madsen)
> 9. Re: How to debug this specific issue? (Steve Davies)
> 10. Re: Should I move to 1.6 or 1.8, or stay with 1.4?
> (Zeeshan Zakaria)
> 
> _______________________________________________
> --Bandwidth and Colocation Provided by http://www.api-digital.com--
> 
> AstriCon 2010 - October 26-28 Washington, DC
> Register Now: http://www.astricon.net/
> 
> asterisk-users mailing list
> To UNSUBSCRIBE or update options visit:
> http://lists.digium.com/mailman/listinfo/asterisk-users

Tilghman Lesher

2010-Aug-26 15:29 UTC

head link

[asterisk-users] Timecondition fallthrough on 2nd GSM Modem, First modem and ZAP's are all fine

On Thursday 26 August 2010 03:41:11 Raimund Sacherer
wrote:> No, the mISDN line one and two are fine, but when I get a call on line 3
> something with the time is wrong. Timeconditions fall through to off-hours
> even if the time of the call is clearly inside business hours, here a log
> excerpt:
Let's take these one at a time:
> [Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing
> [4 at timeconditions:1] GotoIfTime("mISDN/3-u10744", 
> "15:00-18:59|mon-thu|*|*?ivr-7|s|1") in new stack  
The time is 11:04, which is not within the times specified (15:00-18:59).
> [Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing
> [4 at timeconditions:2] Goto("mISDN/3-u10744",
"timeconditions|5|1")
> in new stack  
> [Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Goto (timeconditions,5,1)
> [Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing
> [5 at timeconditions:1] GotoIfTime("mISDN/3-u10744",
> "08:01-14:59|fri|*|*?ivr-7|s|1") in new stack  
The day is Thursday, which is not within the days specified (fri).
> [Aug 26 11:04:37] VERBOSE[9172] logger.c:     -- Executing
> [5 at timeconditions:2] Goto("mISDN/3-u10744",
"ivr-5|s|1") in new stack
-- 
Tilghman Lesher
Digium, Inc. | Senior Software Developer
twitter: Corydon76 | IRC: Corydon76-dig (Freenode)
Check us out at: www.digium.com & www.asterisk.org

Possibly Parallel Threads

Search for more possibly parallel threads

asterisk users - Aug 2010 - Timecondition fallthrough on 2nd GSM Modem, First modem and ZAP's are all fine

[asterisk-users] Timecondition fallthrough on 2nd GSM Modem, First modem and ZAP's are all fine

[asterisk-users] Timecondition fallthrough on 2nd GSM Modem, First modem and ZAP's are all fine

Possibly Parallel Threads