thr3ads.net - asterisk users - [Asterisk-Users] asterisk shutdown [Jun 2006]

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

Anton Krall

2006-Jun-28 08:47 UTC

[Asterisk-Users] asterisk shutdown

Guys.

Ive seen on my asterisk messages log that asterisk has shutdown itself about
12 times in 5 days... The logs show nothing but:

[Jun 28 09:40:02] WARNING[3172]: Unicall/4 event Drop call
[Jun 28 09:40:02] WARNING[3172]: Unicall/4 event Release call
[Jun 28 09:40:02] VERBOSE[3172]: [Jun 28 09:40:02]     -- Unicall/4 released
[Jun 28 09:40:02] VERBOSE[3084]: [Jun 28 09:40:02] Asterisk cleanly ending
(15).
[Jun 28 09:40:03] VERBOSE[28320]: [Jun 28 09:40:03] Asterisk Event Logger
Started /var/log/asterisk/event_log
[Jun 28 09:41:01] VERBOSE[28368]: [Jun 28 09:41:01] Beginning asterisk
shutdown....
[Jun 28 09:41:01] VERBOSE[28368]: [Jun 28 09:41:01] Executing last minute
cleanups
[Jun 28 09:41:01] VERBOSE[28368]: [Jun 28 09:41:01] Asterisk cleanly ending
(15).
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01] Asterisk Event Logger
Started /var/log/asterisk/event_log
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01]   == Parsing
'/etc/asterisk/dnsmgr.conf': [Jun 28 09:41:01] VERBOSE[28457]: [Jun 28
09:41:01]   == Parsin
g '/etc/asterisk/dnsmgr.conf': [Jun 28 09:41:01] Found
[Jun 28 09:41:01] NOTICE[28457]: Managed DNS entries will be refreshed every
1200 seconds.
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01] Asterisk Dynamic Loader
loading preload modules:
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01]   == Parsing
'/etc/asterisk/modules.conf': [Jun 28 09:41:01] VERBOSE[28457]: [Jun 28
09:41:01]   == Parsi
ng '/etc/asterisk/modules.conf': [Jun 28 09:41:01] Found
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01]   == Manager registered
action Ping
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01]   == Manager registered
action Events
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01]   == Manager registered
action Logoff
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01]   == Manager registered
action Hangup
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01]   == Manager registered
action Status
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01]   == Manager registered
action Setvar
[Jun 28 09:41:01] VERBOSE[28457]: [Jun 28 09:41:01]   == Manager registered
action Getvar

As you can see, there are no noticeable errors or anything so.. Anybody has
seen this before? Is there any way to make asterisk more verbose? Im running
it as -vvvvvvvvcg 

Any hints?

Doug Lytle

2006-Jun-28 09:42 UTC

head link

[Asterisk-Users] asterisk shutdown

Anton Krall wrote:> Guys.
>
> Ive seen on my asterisk messages log that asterisk has shutdown itself
about
> 12 times in 5 days... The logs show nothing but:
>   
What version?

I'm running 1.2.9.1 and saw one of my Asterisk process, this morning, 
just shut down for no apparent reason.  I didn't have a console at the 
time.  The logs don't show anything.

Doug

-- 

Ben Franklin quote:

"Those who would give up Essential Liberty to purchase a little Temporary
Safety, deserve neither Liberty nor Safety."

Anton Krall

2006-Jun-30 19:11 UTC

head link

[Asterisk-Users] asterisk shutdown

No log entries yet that might show whats happening and you are correct, I
cant run under strace as it would hit performance quite bad.

:( I will continue to look into the logs and hope something will show up so
I can post further.. If anybody else experiencing this can come up with some
log entry, please share. 

|-----Original Message-----
|From: asterisk-users-bounces@lists.digium.com 
|[mailto:asterisk-users-bounces@lists.digium.com] On Behalf Of 
|Tzafrir Cohen
|Sent: Friday, June 30, 2006 2:13 AM
|To: asterisk-users@lists.digium.com
|Subject: Re: [Asterisk-Users] asterisk shutdown
|
|On Thu, Jun 29, 2006 at 10:54:58PM -0500, Anton Krall wrote:
|> So, no answers?  Nobody knowd why this might be happening? 
|Nobody else 
|> experiencing this?
|
|Is this a reproducable issue? Have you turned on verbosity and 
|debug and log them (e.g. the "full" log)?
|
|If still no messages and this is reproducable, consider 
|running asteriskunder strace (-f). Though I figure that this 
|could be a major performance hit.
|
|-- 
|Tzafrir Cohen      sip:tzafrir@local.xorcom.com
|icq#16849755       iax:tzafrir@local.xorcom.com
|+972-50-7952406           
|tzafrir.cohen@xorcom.com  http://www.xorcom.com 
|_______________________________________________
|--Bandwidth and Colocation provided by Easynews.com --
|
|Asterisk-Users mailing list
|To UNSUBSCRIBE or update options visit:
|   http://lists.digium.com/mailman/listinfo/asterisk-users
|

Anton Krall

2006-Jul-04 14:38 UTC

head link

[Asterisk-Users] asterisk shutdown

Guys.

I was able to put asterisk on debug for a while and another shutdown took
place, here are the logs... I was wondering.. I notice that we are using a
lot of manager actions and can it be that if you place a lot of manager
actions in a short period of time, that it makes asterisk do a shutdown?
Maybe a bug?

[Jul  4 12:59:59] VERBOSE[8953]: [Jul  4 12:59:59]   == Parsing
'/etc/asterisk/manager.conf': [Jul  4 12:59:59] VERBOSE[8953]: [Jul  4
12:59:59]   == Parsing
 '/etc/asterisk/manager.conf': [Jul  4 12:59:59] Found
[Jul  4 12:59:59] DEBUG[8953]: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for
peer
[Jul  4 12:59:59] DEBUG[8953]: 127.0.0.1/255.255.255.255/255.255.255.255
appended to acl for peer
[Jul  4 12:59:59] DEBUG[8953]: 10.0.0.0/255.0.0.0/255.0.0.0 appended to acl
for peer
[Jul  4 12:59:59] DEBUG[8953]: ##### Testing 127.0.0.1 with 0.0.0.0
[Jul  4 12:59:59] DEBUG[8953]: ##### Testing 127.0.0.1 with 127.0.0.1
[Jul  4 12:59:59] DEBUG[8953]: ##### Testing 127.0.0.1 with 10.0.0.0
[Jul  4 12:59:59] DEBUG[8953]: Manager received command 'Command'
[Jul  4 12:59:59] DEBUG[8953]: Manager received command 'Logoff'
[Jul  4 12:59:59] DEBUG[8954]: Manager received command 'Login'
[Jul  4 12:59:59] VERBOSE[8954]: [Jul  4 12:59:59]   == Parsing
'/etc/asterisk/manager.conf': [Jul  4 12:59:59] VERBOSE[8954]: [Jul  4
12:59:59]   == Parsing
 '/etc/asterisk/manager.conf': [Jul  4 12:59:59] Found
[Jul  4 12:59:59] DEBUG[8954]: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for
peer
[Jul  4 12:59:59] DEBUG[8954]: 127.0.0.1/255.255.255.255/255.255.255.255
appended to acl for peer
[Jul  4 12:59:59] DEBUG[8954]: 10.0.0.0/255.0.0.0/255.0.0.0 appended to acl
for peer
[Jul  4 12:59:59] DEBUG[8954]: ##### Testing 127.0.0.1 with 0.0.0.0
[Jul  4 12:59:59] DEBUG[8954]: ##### Testing 127.0.0.1 with 127.0.0.1
[Jul  4 12:59:59] DEBUG[8954]: ##### Testing 127.0.0.1 with 10.0.0.0
[Jul  4 12:59:59] DEBUG[8954]: Manager received command 'Command'
[Jul  4 12:59:59] DEBUG[8954]: Manager received command 'Logoff'
[Jul  4 12:59:59] DEBUG[8955]: Manager received command 'Login'
[Jul  4 12:59:59] VERBOSE[8955]: [Jul  4 12:59:59]   == Parsing
'/etc/asterisk/manager.conf': [Jul  4 12:59:59] VERBOSE[8955]: [Jul  4
12:59:59]   == Parsing
 '/etc/asterisk/manager.conf': [Jul  4 12:59:59] Found
[Jul  4 12:59:59] DEBUG[8955]: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for
peer
[Jul  4 12:59:59] DEBUG[8955]: 127.0.0.1/255.255.255.255/255.255.255.255
appended to acl for peer
[Jul  4 12:59:59] DEBUG[8955]: 10.0.0.0/255.0.0.0/255.0.0.0 appended to acl
for peer
[Jul  4 12:59:59] DEBUG[8955]: ##### Testing 127.0.0.1 with 0.0.0.0
[Jul  4 12:59:59] DEBUG[8955]: ##### Testing 127.0.0.1 with 127.0.0.1
[Jul  4 12:59:59] DEBUG[8955]: ##### Testing 127.0.0.1 with 10.0.0.0
[Jul  4 12:59:59] DEBUG[8955]: Manager received command 'Command'
[Jul  4 12:59:59] DEBUG[8955]: Manager received command 'Logoff'
[Jul  4 13:00:02] DEBUG[8663]: update_call_counter(114) - decrement call
limit counter
[Jul  4 13:00:02] DEBUG[8663]: Acked pending invite 102
[Jul  4 13:00:02] DEBUG[8663]: Stopping retransmission on
'4137b6ce4afdb5ce6d0d36157793f7ae@192.168.1.50' of Request 102: Match
Found
[Jul  4 13:00:02] DEBUG[8663]: Exiting with DIALSTATUS=CANCEL.
[Jul  4 13:00:02] VERBOSE[8663]: [Jul  4 13:00:02]   == Spawn extension
(internalphones, 114, 7) exited non-zero on 'SIP/101-f035'
[Jul  4 13:00:02] VERBOSE[8669]: [Jul  4 13:00:02]   == End MixMonitor
Recording SIP/101-f035
[Jul  4 13:00:02] VERBOSE[8669]: [Jul  4 13:00:02]   == Executing [chmod 777
/digrec/20060704-125935-1152035975.864-from-101-to-114.wav]
[Jul  4 13:00:02] DEBUG[8663]: Spy ?d removed from channel SIP/101-f035
[Jul  4 13:00:02] DEBUG[8663]: cdr_mysql: inserting a CDR record.
[Jul  4 13:00:02] DEBUG[8663]: cdr_mysql: SQL command as follows: INSERT
INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duratio
n,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES
('2006-07-04 12:59:35','\"Recepcion\"
<101>','101','114','internalphones',
'SIP/101-f03
5','SIP/114-b115','Dial','SIP/114|120|rtwTW',27,0,'NO
ANSWER',3,'Recepcion','1152035975.864','[Recepcion ->
Miguel Angel Sanz] ')
[Jul  4 13:00:02] DEBUG[8663]: update_call_counter(101) - decrement call
limit counter
[Jul  4 13:00:02] DEBUG[8319]: update_call_counter(101) - decrement call
limit counter
[Jul  4 13:00:02] DEBUG[8319]: Acked pending invite 102
[Jul  4 13:00:02] DEBUG[8319]: Stopping retransmission on
'129c0d1d2f0da8c15c397160686369d1@192.168.1.50' of Request 102: Match
Found
[Jul  4 13:00:02] DEBUG[8305]: Didn't get a frame from channel: SIP/101-d287
[Jul  4 13:00:02] DEBUG[8305]: Bridge stops bridging channels UniCall/4-1
and SIP/101-d287
[Jul  4 13:00:02] DEBUG[8305]: update_call_counter(101) - decrement call
limit counter
[Jul  4 13:00:02] DEBUG[8227]: Didn't get a frame from channel: SIP/101-a2b9
[Jul  4 13:00:02] DEBUG[8227]: Bridge stops bridging channels Zap/35-1 and
SIP/101-a2b9
[Jul  4 13:00:02] DEBUG[8227]: update_call_counter(101) - decrement call
limit counter
[Jul  4 13:00:02] DEBUG[8281]: Didn't get a frame from channel: SIP/114-68c7
[Jul  4 13:00:02] DEBUG[8281]: Bridge stops bridging channels UniCall/1-1
and SIP/114-68c7
[Jul  4 13:00:02] DEBUG[8281]: update_call_counter(114) - decrement call
limit counter
[Jul  4 13:00:02] DEBUG[8025]: Didn't get a frame from channel: SIP/117-140e
[Jul  4 13:00:02] DEBUG[8025]: Bridge stops bridging channels UniCall/7-1
and SIP/117-140e
[Jul  4 13:00:02] DEBUG[8025]: update_call_counter(117) - decrement call
limit counter
[Jul  4 13:00:02] DEBUG[7456]: Didn't get a frame from channel: SIP/101-79f8
[Jul  4 13:00:02] DEBUG[7456]: Bridge stops bridging channels UniCall/5-1
and SIP/101-79f8
[Jul  4 13:00:02] DEBUG[7456]: update_call_counter(101) - decrement call
limit counter
[Jul  4 13:00:02] DEBUG[7062]: Didn't get a frame from channel: UniCall/2-1
[Jul  4 13:00:02] DEBUG[7062]: Bridge stops bridging channels SIP/124-e596
and UniCall/2-1
[Jul  4 13:00:02] DEBUG[7062]: Hangup: channel: 2 index = 0, normal = 15,
callwait = -1, thirdcall = -1
[Jul  4 13:00:02] DEBUG[7062]: Updated conferencing on 2, with 0 conference
users
[Jul  4 13:00:02] VERBOSE[7062]: [Jul  4 13:00:02]     -- Hungup
'UniCall/2-1'
[Jul  4 13:00:02] NOTICE[8319]: No one is answering queue 'recepcion'
(1/0/0)
[Jul  4 13:00:02] VERBOSE[8319]: [Jul  4 13:00:02]     -- User disconnected
from queue recepcion when they almost made it
[Jul  4 13:00:02] VERBOSE[8319]: [Jul  4 13:00:02]   == Spawn extension
(recepcion, s, 3) exited non-zero on 'UniCall/8-1'
[Jul  4 13:00:02] DEBUG[8305]: Exiting with DIALSTATUS=ANSWER.
[Jul  4 13:00:02] VERBOSE[8305]: [Jul  4 13:00:02]   == Spawn extension
(ivrextensions, 101, 12) exited non-zero on 'UniCall/4-1'
[Jul  4 13:00:02] DEBUG[8227]: Exiting with DIALSTATUS=ANSWER.
[Jul  4 13:00:02] DEBUG[8319]: Spy MixMonitor removed from channel
UniCall/8-1
[Jul  4 13:00:02] VERBOSE[8227]: [Jul  4 13:00:02]   == Spawn extension
(ivrextensions, 101, 12) exited non-zero on 'Zap/35-1'
[Jul  4 13:00:02] DEBUG[8281]: Exiting with DIALSTATUS=ANSWER.
[Jul  4 13:00:02] VERBOSE[8281]: [Jul  4 13:00:02]   == Spawn extension
(internalphones, 114, 7) exited non-zero on 'UniCall/1-1'
[Jul  4 13:00:02] DEBUG[8305]: Spy MixMonitor removed from channel
UniCall/4-1
[Jul  4 13:00:02] VERBOSE[8305]: [Jul  4 13:00:02]     -- Stopped music on
hold on UniCall/4-1
[Jul  4 13:00:02] VERBOSE[8324]: [Jul  4 13:00:02]   == End MixMonitor
Recording UniCall/8-1
[Jul  4 13:00:02] VERBOSE[8324]: [Jul  4 13:00:02]   == Executing [chmod 777
/digrec/20060704-125837-1152035916.858-from-5552792400-to-101.wav]
[Jul  4 13:00:02] VERBOSE[8319]: [Jul  4 13:00:02]     -- Stopped music on
hold on UniCall/8-1
[Jul  4 13:00:02] DEBUG[8025]: Exiting with DIALSTATUS=ANSWER.
[Jul  4 13:00:02] VERBOSE[8025]: [Jul  4 13:00:02]   == Spawn extension
(internalphones, 117, 7) exited non-zero on 'UniCall/7-1'
[Jul  4 13:00:02] VERBOSE[8312]: [Jul  4 13:00:02]   == End MixMonitor
Recording UniCall/4-1
[Jul  4 13:00:02] VERBOSE[8312]: [Jul  4 13:00:02]   == Executing [chmod 777
/digrec/20060704-125831-1152035910.856-from-5512244045-to-101.wav]
[Jul  4 13:00:02] DEBUG[8227]: Spy MixMonitor removed from channel Zap/35-1
[Jul  4 13:00:02] VERBOSE[8227]: [Jul  4 13:00:02]     -- Stopped music on
hold on Zap/35-1
[Jul  4 13:00:02] DEBUG[7456]: Exiting with DIALSTATUS=ANSWER.
[Jul  4 13:00:02] VERBOSE[7456]: [Jul  4 13:00:02]   == Spawn extension
(ivrextensions, 101, 12) exited non-zero on 'UniCall/5-1'
[Jul  4 13:00:02] DEBUG[8281]: Spy MixMonitor removed from channel
UniCall/1-1
[Jul  4 13:00:02] VERBOSE[8294]: [Jul  4 13:00:02]   == End MixMonitor
Recording Zap/35-1
[Jul  4 13:00:02] VERBOSE[8294]: [Jul  4 13:00:02]   == Executing [chmod 777
/digrec/20060704-125824-1152035898.852-from-unknown-to-101.wav]
[Jul  4 13:00:02] DEBUG[8025]: Spy MixMonitor removed from channel
UniCall/7-1
[Jul  4 13:00:02] VERBOSE[8287]: [Jul  4 13:00:02]   == End MixMonitor
Recording SIP/101-13f3
[Jul  4 13:00:02] VERBOSE[8287]: [Jul  4 13:00:02]   == Executing [chmod 777
/digrec/20060704-125821-1152035901.853-from-101-to-114.wav]
[Jul  4 13:00:02] DEBUG[7456]: Spy MixMonitor removed from channel
UniCall/5-1
[Jul  4 13:00:02] VERBOSE[7456]: [Jul  4 13:00:02]     -- Stopped music on
hold on UniCall/5-1
[Jul  4 13:00:02] VERBOSE[8031]: [Jul  4 13:00:02]   == End MixMonitor
Recording SIP/101-150e
[Jul  4 13:00:02] VERBOSE[8031]: [Jul  4 13:00:02]   == Executing [chmod 777
/digrec/20060704-125726-1152035846.844-from-101-to-117.wav]
[Jul  4 13:00:02] DEBUG[7062]: Exiting with DIALSTATUS=ANSWER.
[Jul  4 13:00:02] DEBUG[7062]: SIP/124-e596 hungup
[Jul  4 13:00:02] VERBOSE[7461]: [Jul  4 13:00:02]   == End MixMonitor
Recording UniCall/5-1
[Jul  4 13:00:02] VERBOSE[7461]: [Jul  4 13:00:02]   == Executing [chmod 777
/digrec/20060704-125424-1152035663.822-from-5555892534-to-101.wav]
[Jul  4 13:00:02] DEBUG[22699]: Stopping retransmission on
'4137b6ce4afdb5ce6d0d36157793f7ae@192.168.1.50' of Request 102: Match
Found
[Jul  4 13:00:02] DEBUG[8305]: cdr_mysql: inserting a CDR record.
[Jul  4 13:00:02] DEBUG[8305]: cdr_mysql: SQL command as follows: INSERT
INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duratio
n,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES
('2006-07-04 12:58:30','\"5512244045\"
<5512244045>','5512244045','101','ivrextensions'
,
'UniCall/4-1','SIP/101-d287','Dial','SIP/101|120|rtwTW',92,92,'ANSWERED',3,'
Recepcion ','1152035910.856','')
[Jul  4 13:00:02] VERBOSE[7062]: [Jul  4 13:00:02]   == Spawn extension
(internalphones, 56032890, 3) exited non-zero on 'SIP/124-e596'
[Jul  4 13:00:02] VERBOSE[22635]: [Jul  4 13:00:02] Beginning asterisk
shutdown....
[Jul  4 13:00:02] DEBUG[7062]: Spy MixMonitor removed from channel
SIP/124-e596
[Jul  4 13:00:02] DEBUG[8305]: Hangup: channel: 4 index = 0, normal = 21,
callwait = -1, thirdcall = -1
[Jul  4 13:00:02] DEBUG[8305]: Updated conferencing on 4, with 0 conference
users
[Jul  4 13:00:02] VERBOSE[8305]: [Jul  4 13:00:02]     -- Hungup
'UniCall/4-1'
[Jul  4 13:00:02] VERBOSE[7068]: [Jul  4 13:00:02]   == End MixMonitor
Recording SIP/124-e596
[Jul  4 13:00:02] VERBOSE[7068]: [Jul  4 13:00:02]   == Executing [chmod 777
/digrec/20060704-125136-1152035496.797-from-124-to-56032890.wav]
[Jul  4 13:00:02] DEBUG[22699]: Stopping retransmission on
'7610b353-3520e895-47d32a36@192.168.1.144' of Response 2: Match Found
[Jul  4 13:00:02] DEBUG[8319]: cdr_mysql: inserting a CDR record.
[Jul  4 13:00:02] DEBUG[8319]: cdr_mysql: SQL command as follows: INSERT
INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duratio
n,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES
('2006-07-04 12:58:36','\"5552792400\"
<5552792400>','5552792400','s','recepcion',
'Uni
Call/8-1','SIP/101-3237','Queue','recepcion|t',86,86,'ANSWERED',3,'Recepcion
','1152035916.858','')
[Jul  4 13:00:02] DEBUG[8319]: Hangup: channel: 8 index = 0, normal = 33,
callwait = -1, thirdcall = -1
[Jul  4 13:00:02] DEBUG[8319]: Updated conferencing on 8, with 0 conference
users
[Jul  4 13:00:02] VERBOSE[8319]: [Jul  4 13:00:02]     -- Hungup
'UniCall/8-1'
[Jul  4 13:00:02] DEBUG[8227]: cdr_mysql: inserting a CDR record.
[Jul  4 13:00:02] DEBUG[8227]: cdr_mysql: SQL command as follows: INSERT
INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duratio
n,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES
('2006-07-04 12:58:23','\"NO CID\"
<No_Caller_ID>','No_Caller_ID','101','ivrextensions'
,
'Zap/35-1','SIP/101-a2b9','Dial','SIP/101|120|rtwTW',99,99,'ANSWERED',3,'Rec
epcion ','1152035898.852','')
[Jul  4 13:00:02] DEBUG[8227]: Hangup: channel: 35 index = 0, normal = 47,
callwait = -1, thirdcall = -1
[Jul  4 13:00:02] DEBUG[8227]: disabled echo cancellation on channel 35
[Jul  4 13:00:02] DEBUG[8227]: Set option TDD MODE, value: OFF(0) on
Zap/35-1
[Jul  4 13:00:02] DEBUG[8227]: Updated conferencing on 35, with 0 conference
users
[Jul  4 13:00:02] VERBOSE[8227]: [Jul  4 13:00:02]     -- Hungup
'Zap/35-1'
[Jul  4 13:00:02] DEBUG[8281]: cdr_mysql: inserting a CDR record.
[Jul  4 13:00:02] DEBUG[8281]: cdr_mysql: SQL command as follows: INSERT
INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duratio
n,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES
('2006-07-04 12:58:21','\"5553749905\"
<5553749905>','5553749905','114','internalphones
',
'SIP/101-13f3','SIP/114-68c7','Dial','SIP/114|120|rtwTW',101,98,'ANSWERED',3
,'Recepcion','1152035901.853','[Recepcion -> Miguel Angel
Sanz] ')
[Jul  4 13:00:02] DEBUG[8281]: cdr_mysql: inserting a CDR record.
[Jul  4 13:00:02] DEBUG[8281]: cdr_mysql: SQL command as follows: INSERT
INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duratio
n,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES
('2006-07-04 12:57:50','\"5553749905\"
<5553749905>','5553749905','114','internalphones
',
'UniCall/1-1','SIP/101-a912','Dial','SIP/101|120|rtwTW',132,132,'ANSWERED',3
,'Recepcion','1152035870.848','')
[Jul  4 13:00:02] DEBUG[8281]: Hangup: channel: 1 index = 0, normal = 12,
callwait = -1, thirdcall = -1
[Jul  4 13:00:02] DEBUG[8281]: Updated conferencing on 1, with 0 conference
users
[Jul  4 13:00:02] VERBOSE[8281]: [Jul  4 13:00:02]     -- Hungup
'UniCall/1-1'
[Jul  4 13:00:02] DEBUG[7456]: cdr_mysql: inserting a CDR record.
[Jul  4 13:00:02] DEBUG[7456]: cdr_mysql: SQL command as follows: INSERT
INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duratio
n,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES
('2006-07-04 12:54:23','\"5555892534\"
<5555892534>','5555892534','101','ivrextensions'
,
'UniCall/5-1','SIP/101-79f8','Dial','SIP/101|120|rtwTW',339,339,'ANSWERED',3
,'Recepcion ','1152035663.822','')
[Jul  4 13:00:02] DEBUG[7456]: Hangup: channel: 5 index = 0, normal = 24,
callwait = -1, thirdcall = -1
[Jul  4 13:00:02] DEBUG[7456]: Updated conferencing on 5, with 0 conference
users
[Jul  4 13:00:02] VERBOSE[7456]: [Jul  4 13:00:02]     -- Hungup
'UniCall/5-1'
[Jul  4 13:00:02] DEBUG[7062]: cdr_mysql: inserting a CDR record.
[Jul  4 13:00:02] DEBUG[7062]: cdr_mysql: SQL command as follows: INSERT
INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duratio
n,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES
('2006-07-04 12:51:36','\"Departamento Contabilidad\"
<124>','124','56032890','internal
phones',
'SIP/124-e596','UniCall/2-1','Dial','Unicall/g1/56032890|60|wtWT/n',506,490,
'ANSWERED',3,'Departamento
Contab','1152035496.797','')
[Jul  4 13:00:02] DEBUG[7062]: update_call_counter(124) - decrement call
limit counter
[Jul  4 13:00:02] DEBUG[8025]: cdr_mysql: inserting a CDR record.
[Jul  4 13:00:02] DEBUG[8025]: cdr_mysql: SQL command as follows: INSERT
INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duratio
n,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES
('2006-07-04 12:57:26','\"5553947467\"
<5553947467>','5553947467','117','internalphones
',
'SIP/101-150e','SIP/117-140e','Dial','SIP/117|120|rtwTW',156,154,'ANSWERED',
3,'Recepcion','1152035846.844','[Recepcion -> Miriam
Loranca] ')
[Jul  4 13:00:02] DEBUG[8025]: cdr_mysql: inserting a CDR record.
[Jul  4 13:00:02] DEBUG[8025]: cdr_mysql: SQL command as follows: INSERT
INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duratio
n,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES
('2006-07-04 12:57:04','\"5553947467\"
<5553947467>','5553947467','117','internalphones
',
'UniCall/7-1','SIP/101-9a84','Dial','SIP/101|120|rtwTW',178,178,'ANSWERED',3
,'Recepcion','1152035824.842','')
[Jul  4 13:00:02] DEBUG[8025]: Hangup: channel: 7 index = 0, normal = 30,
callwait = -1, thirdcall = -1
[Jul  4 13:00:02] DEBUG[8025]: Updated conferencing on 7, with 0 conference
users
[Jul  4 13:00:02] VERBOSE[8025]: [Jul  4 13:00:02]     -- Hungup
'UniCall/7-1'
[Jul  4 13:00:02] VERBOSE[22635]: [Jul  4 13:00:02] Executing last minute
cleanups
[Jul  4 13:00:02] VERBOSE[22635]: [Jul  4 13:00:02]   == Destroying
musiconhold processes
[Jul  4 13:00:02] DEBUG[22635]: killing 22693!
[Jul  4 13:00:02] DEBUG[22699]: Stopping retransmission on
'057c2e1a2c4b0ea333e3dde87e78037a@192.168.1.50' of Request 103: Match
Found
[Jul  4 13:00:02] DEBUG[22699]: Stopping retransmission on
'4137b6ce4afdb5ce6d0d36157793f7ae@192.168.1.50' of Request 102: Match
Not
Found
[Jul  4 13:00:02] WARNING[22696]: Unicall/2 event Drop call
[Jul  4 13:00:02] DEBUG[22696]: CRN 32840 - Doing a release call
[Jul  4 13:00:02] WARNING[22696]: Unicall/2 event Release call
[Jul  4 13:00:02] DEBUG[22696]: CRN 32840 - Call released
[Jul  4 13:00:02] VERBOSE[22696]: [Jul  4 13:00:02]     -- Unicall/2
released
[Jul  4 13:00:02] DEBUG[22696]: disabled echo cancellation on channel 2
[Jul  4 13:00:02] DEBUG[22699]: Stopping retransmission on
'10f2e5cc-a418ab6-c9817029@192.168.1.141' of Request 102: Match Found
[Jul  4 13:00:02] DEBUG[22699]: Stopping retransmission on
'255227a0018cb6c510f55af62ad1d764@192.168.1.50' of Request 103: Match
Found
[Jul  4 13:00:02] DEBUG[22699]: Stopping retransmission on
'129c0d1d2f0da8c15c397160686369d1@192.168.1.50' of Request 102: Match
Found
[Jul  4 13:00:02] DEBUG[22635]: mpg123 pid 22693 and child died after 7424
bytes read
[Jul  4 13:00:02] VERBOSE[22635]: [Jul  4 13:00:02] Asterisk cleanly ending
(15).
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03] Asterisk Event Logger
Started /var/log/asterisk/event_log
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Parsing
'/etc/asterisk/dnsmgr.conf': [Jul  4 13:00:03] VERBOSE[9072]: [Jul  4
13:00:03]   == Parsing
'/etc/asterisk/dnsmgr.conf': [Jul  4 13:00:03] Found
[Jul  4 13:00:03] NOTICE[9072]: Managed DNS entries will be refreshed every
1200 seconds.
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03] Asterisk Dynamic Loader
loading preload modules:
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Parsing
'/etc/asterisk/modules.conf': [Jul  4 13:00:03] VERBOSE[9072]: [Jul  4
13:00:03]   == Parsing
 '/etc/asterisk/modules.conf': [Jul  4 13:00:03] Found
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action Ping
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action Events
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action Logoff
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action Hangup
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action Status
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action Setvar
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action Getvar
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action Redirect
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action Originate
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action Command
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action ExtensionState
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action AbsoluteTimeout
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action MailboxStatus
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action MailboxCount
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Manager registered
action ListCommands
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Parsing
'/etc/asterisk/manager.conf': [Jul  4 13:00:03] VERBOSE[9072]: [Jul  4
13:00:03]   == Parsing
 '/etc/asterisk/manager.conf': [Jul  4 13:00:03] Found
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03] Asterisk Management
interface listening on port 5038
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Parsing
'/etc/asterisk/cdr.conf': [Jul  4 13:00:03] VERBOSE[9072]: [Jul  4
13:00:03]
== Parsing '/e
tc/asterisk/cdr.conf': [Jul  4 13:00:03] Found
[Jul  4 13:00:03] NOTICE[9072]: CDR simple logging enabled.
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Parsing
'/etc/asterisk/rtp.conf': [Jul  4 13:00:03] VERBOSE[9072]: [Jul  4
13:00:03]
== Parsing '/e
tc/asterisk/rtp.conf': [Jul  4 13:00:03] Found
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == RTP Allocating from
port range 4460 -> 4560
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03] Asterisk PBX Core
Initializing
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03] Registering builtin
applications:
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [AbsoluteTimeout]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'AbsoluteTimeout'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [Answer]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'Answer'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [BackGround]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'BackGround'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [Busy]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'Busy'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [Congestion]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'Congestion'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [DigitTimeout]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'DigitTimeout'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [Goto]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'Goto'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [GotoIf]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'GotoIf'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [GotoIfTime]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'GotoIfTime'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [ExecIfTime]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'ExecIfTime'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [Hangup]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'Hangup'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [NoOp]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'NoOp'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [Progress]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'Progress'
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]  [ResetCDR]
[Jul  4 13:00:03] VERBOSE[9072]: [Jul  4 13:00:03]   == Registered
application 'ResetCDR'

Reasonably Related Threads

Search for more seemingly similar threads

asterisk users - Jun 2006 - asterisk shutdown

[Asterisk-Users] asterisk shutdown

[Asterisk-Users] asterisk shutdown

[Asterisk-Users] asterisk shutdown

[Asterisk-Users] asterisk shutdown

Reasonably Related Threads