[Home]

Summary:ASTERISK-06663: Logger.c Cause segfault
Reporter:wILMAR cAMPOS (willcampos)Labels:
Date Opened:2006-03-30 10:21:03.000-0600Date Closed:2006-04-10 21:07:11
Priority:CriticalRegression?No
Status:Closed/CompleteComponents:Core/General
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:Hello,
My system segfault, this is the information i got from the dump.  The thing is I dont have enable any logrotate or something like that, it seems is controlled internally in asterisk.

Other strange thing is the system did the log rotate several times on the same hour.

Thanks in advance for any help you can give.

Wilmar


****** ADDITIONAL INFORMATION ******

/var/log/asterisk directory:
-rw-r--r--   1 root root   1147131 2006-03-23 12:00 messages.0
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.1
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.10
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.11
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.12
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.13
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.14
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.15
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.16
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.17
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.18
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.19
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.2
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.20
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.21
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.22
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.23
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.24
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.25
-rw-r--r--   1 root root     71401 2006-03-23 13:02 messages.26
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.3
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.4
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.5
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.6
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.7
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.8
-rw-r--r--   1 root root         0 2006-03-23 12:01 messages.9

CORE:

#0  0x4015725e in vfprintf () from /lib/libc.so.6
(gdb) bt
#0  0x4015725e in vfprintf () from /lib/libc.so.6
#1  0x40177860 in vsnprintf () from /lib/libc.so.6
#2  0x40160134 in snprintf () from /lib/libc.so.6
#3  0x0805e1e1 in config_text_file_load (database=0xbd3c29a4 "\024¼\027@\017", table=0xbd3c28a4 "", filename=0x80ed721 "logger.conf", cfg=0x41782a58) at config.c:549
#4  0x0805d661 in ast_config_internal_load (filename=0x811505c "wü\016\bpá\005\b", cfg=0xbd3c28a4) at config.c:959
ASTERISK-1  0x0805e06c in ast_config_load (filename=0xbd3c2754 "") at config.c:976
ASTERISK-2  0x08057f81 in init_logger_chain () at logger.c:311
ASTERISK-3  0x08056d6b in reload_logger (rotate=1) at logger.c:434
ASTERISK-4  0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-5  0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-6 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-7 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-8 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-9 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-10 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c ", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-11 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-12 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-13 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-14 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-15 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-16 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-17 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-18 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-19 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c ", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-20 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-21 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-22 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-23 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-24 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-25 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-26 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-27 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-28 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c ", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-29 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-30 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-31 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-32 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-33 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-34 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-35 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-36 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-37 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c ", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
---Type <return> to continue, or q <return> to quit---
ASTERISK-38 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-39 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-40 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-41 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-42 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-43 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c ", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-44 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-45 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-46 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-47 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-48 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-49 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-50 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-51 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-52 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c ", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-53 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-54 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-55 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-56 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-57 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-58 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-59 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-60 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-61 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c ", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-62 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-63 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-64 0x08057566 in ast_log (level=5, file=0x80ed5f2 "logger.c", line=898, function=0x80ed6eb "ast_verbose", fmt=0x81003a6 "%s") at logger.c:805
ASTERISK-65 0x08057b9b in ast_verbose (fmt=0x81003a6 "%s") at logger.c:898
ASTERISK-66 0x08057090 in reload_logger (rotate=1) at logger.c:457
ASTERISK-67 0x08057566 in ast_log (level=0, file=0x80efab8 "channel.c", line=2041, function=0x80efc62 "ast_indicate",
   fmt=0x80f1120 "Driver for channel '%s' does not support indication %d, emulating it\n") at logger.c:805
ASTERISK-68 0x08061fcb in ast_indicate (chan=0x41440e50, condition=0) at channel.c:2041
ASTERISK-69 0x40f8645a in wait_for_answer (in=0x41440e50, outgoing=0x417a9d88, to=0xbd3f7cc4, peerflags=0xbd3f861c, sentringing=0xbd3f7cc8, status=0xbd3f7e64 "NOANSWER",
   statussize=256, busystart=0, nochanstart=0, congestionstart=0, priority_jump=0, result=0xbd3c2754) at app_dial.c:584
ASTERISK-70 0x40f82da4 in dial_exec_full (chan=0x41440e50, data=0xbd3f7cc8, peerflags=0xbd3f861c) at app_dial.c:1162
ASTERISK-71 0x40f81c15 in dial_exec (chan=0xbd3c2754, data=0xbd3c2754) at app_dial.c:1601
ASTERISK-72 0x0808c13d in pbx_exec (c=0x41440e50, app=0xbd3c2754, data=0xbd3f86e4, newstack=1) at pbx.c:544
ASTERISK-73 0x40f9f6d0 in lcdial_dial (chan=0x41440e50, data=0xbd3f86e4, providerid=6) at lcdial.c:156
ASTERISK-74 0x40f9f20b in lcdial_exec (chan=0x41440e50, data=0xbd3f8a74) at lcdial.c:277
ASTERISK-75 0x0808c13d in pbx_exec (c=0x41440e50, app=0xbd3c2754, data=0xbd3f8a74, newstack=1) at pbx.c:544
ASTERISK-76 0x40c0a3ca in callingcard_pbx_dial (chan=0x41440e50, cd=0xbd3f9214) at app_prepaid_call.c:439
ASTERISK-77 0x40c0932f in callingcard_exec (chan=0x41440e50, data=0xbd3fd754) at app_prepaid_call.c:966
---Type <return> to continue, or q <return> to quit---
ASTERISK-78 0x0808e172 in pbx_extension_helper (c=0x41440e50, con=0xbd3c2754, context=0x41440fa0 "callingcard", exten=0x41441094 "98898096815505", priority=203, label=0x0,
   callerid=0x0, action=0) at pbx.c:544
ASTERISK-79 0x0808ed9f in __ast_pbx_run (c=0x41440e50) at pbx.c:2218
ASTERISK-80 0x0808fa8f in pbx_thread (data=0xbd3c2754) at pbx.c:2505
ASTERISK-81 0x4002a54e in pthread_start_thread () from /lib/libpthread.so.0
ASTERISK-82 0x401d6b8a in clone () from /lib/libc.so.6
Comments:By: Joshua C. Colp (jcolp) 2006-03-30 12:51:58.000-0600

What does your logger.conf look like?
By: Tilghman Lesher (tilghman) 2006-03-30 13:36:44.000-0600

Are you recording something at the time?
By: Andrey S Pankov (casper) 2006-03-30 15:58:10.000-0600

callingcard_xxx bug? I mean lcdial.c and app_prepaid_call.c


By: Tilghman Lesher (tilghman) 2006-03-30 16:13:39.000-0600

This is the exact symptom he'd have if he was recording an audio file and it reached 2GB in size (on 1.2).  We solved this issue in trunk by going to 64-bit file offsets.

One note:  an uncompressed audio file only hits 2GB at around 37 hours, so unless he's making some very long recordings, this isn't it.  However, the program is still receiving the signal that one of its files has reached the 2GB filesize limit, which results in this exact behavior.  If this is a recording problem, the first question is why he's making a 37 hour recording.  The second question is why isn't he using a compressed audio codec.
By: Andrey S Pankov (casper) 2006-04-10 13:45:55

Any updates here?
By: wILMAR cAMPOS (willcampos) 2006-04-10 13:59:43

Casper, I am so sorry but I realized my updated were not posted...

So, the aplication callingcard or lcdial does not do recording, so I think this does not apply to what happend to me.

I never happend again, the only diference is I an rotating the log's with logrotate daily.
By: BJ Weschke (bweschke) 2006-04-10 21:07:11

Closing until we can get a little more information to reliably reproduce this. willcampos, no one else is able to lab this up and reproduce.