[asterisk-users] sched.c:220 ast_sched_add_variable: Scheduled event in 0 ms?

[Mark G. Thomas]

Hi,

I've been noticing a lot of these messages lately:
  "NOTICE[10235]: sched.c:220 ast_sched_add_variable: Scheduled event in 0 ms?"

Is something broken?  I'm running asterisk-1.4.22.1.

They seem to happen in a number of different places where a beep or 
recording is played, such as when someone leaves voicemail or when 
an AGI script I have plays a time announcement -- lots of different places.

.....
   -- Executing [3 at main-menu:2] Wait("SIP/redacted-09501e28", "1") in new stack
    -- Executing [3 at main-menu:3] VoiceMail("SIP/redacted-09501e28", "10|s") in new stack
    -- <SIP/redacted-09501e28> Playing 'beep' (language 'en')
[Jan 14 12:34:18] NOTICE[10030]: sched.c:220 ast_sched_add_variable: Scheduled event in 0 ms?
    -- Recording the message
    -- x=0, open writing:  /var/spool/asterisk/voicemail/default/10/tmp/EGIKgI format: wav49, 0x9489080
    -- x=1, open writing:  /var/spool/asterisk/voicemail/default/10/tmp/EGIKgI format: gsm, 0x94feaf8
    -- x=2, open writing:  /var/spool/asterisk/voicemail/default/10/tmp/EGIKgI format: wav, 0x950c6e0
    -- User hung up
.....

.....
   -- Executing [3 at main-menu:3] VoiceMail("SIP/redacted-09500428", "10|s") in new stack
    -- <SIP/redacted-09500428> Playing 'beep' (language 'en')
[Jan 14 13:23:05] NOTICE[10235]: sched.c:220 ast_sched_add_variable: Scheduled event in 0 ms?
    -- Recording the message
   -- x=0, open writing:  /var/spool/asterisk/voicemail/default/10/tmp/o9SnHz format: wav49, 0x94feaf8
    -- x=1, open writing:  /var/spool/asterisk/voicemail/default/10/tmp/o9SnHz format: gsm, 0x9489080
    -- x=2, open writing:  /var/spool/asterisk/voicemail/default/10/tmp/o9SnHz format: wav, 0x9523bf8
    -- User hung up
....


.....
   -- Launched AGI Script /var/lib/asterisk/agi-bin/talking-clock.agi
    -- AGI Script Executing Application: (PlayTones) Options: (!523/20,!0/980,!523/20,!0/980,!523/20,!0/980,!523/20,!0/980,!523/20,!0/980,!523/20,!0/980,!523/20,!0/980,!523/20,!0/980,!415/1500)
    -- Playing 'at-tone-time-exactly' (escape_digits=) (sample_offset 0)
[Jan 14 13:35:07] NOTICE[10271]: sched.c:220 ast_sched_add_variable: Scheduled event in 0 ms?
    -- <Zap/5-1> Playing 'digits/1' (language 'en')
    -- <Zap/5-1> Playing 'digits/30' (language 'en')
[Jan 14 13:35:08] NOTICE[10271]: sched.c:220 ast_sched_add_variable: Scheduled event in 0 ms?
    -- <Zap/5-1> Playing 'digits/5' (language 'en')
[Jan 14 13:35:09] NOTICE[10271]: sched.c:220 ast_sched_add_variable: Scheduled event in 0 ms?
    -- <Zap/5-1> Playing 'vm-and' (language 'en')
    -- <Zap/5-1> Playing 'digits/15' (language 'en')
    -- <Zap/5-1> Playing 'seconds' (language 'en')
    -- AGI Script Executing Application: (PlayTones) Options: (!0/500,!523/20,!0/980,!523/20,!0/980,!415/1500)
  == Spawn extension (from-pots1, s, 2) exited non-zero on 'Zap/5-1'
    -- Hungup 'Zap/5-1'
.....

-- 
Mark G. Thomas (Mark at Misty.com)