Dial commands not being logged

bcnx · September 6, 2022, 6:39pm

Hi,

for some reason some info is not in my logfiles, while I’m convinced they used to be. In other installs this is no problem.

What I miss is info like this:

[Sep 6 21:38:05] VERBOSE[3972][C-00001635] pbx.c: – Executing [032172510@intern:1] Set(“SIP/820-00000004”, “CALLERID(num)=14480820”) in new stack
[Sep 6 21:38:05] VERBOSE[3972][C-00001635] pbx.c: – Executing [032172510@intern:2] Dial(“SIP/820-00000004”, “SIP/032172510@lcrtelecom1”) in new stack
[Sep 6 21:38:05] VERBOSE[3972][C-00001635] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 6 21:38:05] VERBOSE[3972][C-00001635] app_dial.c: – Called SIP/032172510@lcrtelecom1
[Sep 6 21:38:05] VERBOSE[3972][C-00001635] app_dial.c: – SIP/lcrtelecom1-00000005 is making progress passing it to SIP/820-00000004
[Sep 6 21:38:05] VERBOSE[3972][C-00001635] app_dial.c: – SIP/lcrtelecom1-00000005 is making progress passing it to SIP/820-00000004
[Sep 6 21:38:05] VERBOSE[3972][C-00001635] app_dial.c: – SIP/lcrtelecom1-00000005 answered SIP/820-00000004
[Sep 6 21:38:05] VERBOSE[3972][C-00001635] rtp_engine.c: – Locally bridging SIP/820-00000004 and SIP/lcrtelecom1-00000005
[Sep 6 21:38:08] VERBOSE[3972][C-00001635] pbx.c: == Spawn extension (intern, 032172510, 2) exited non-zero on ‘SIP/820-00000004’
[Sep 6 21:38:10] NOTICE[26438][C-00001636] chan_sip.c: Failed to authenticate device sip:5001@139.162.225.160;tag=1669567017
[Sep 6 21:38:39] VERBOSE[26438][C-00001637] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 6 21:38:39] VERBOSE[3976][C-00001637] pbx.c: – Executing [032172510@intern:1] Set(“SIP/820-00000006”, “CALLERID(num)=14480820”) in new stack
[Sep 6 21:38:39] VERBOSE[3976][C-00001637] pbx.c: – Executing [032172510@intern:2] Dial(“SIP/820-00000006”, “SIP/032172510@lcrtelecom1”) in new stack
[Sep 6 21:38:39] VERBOSE[3976][C-00001637] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 6 21:38:39] VERBOSE[3976][C-00001637] app_dial.c: – Called SIP/032172510@lcrtelecom1
[Sep 6 21:38:39] VERBOSE[3976][C-00001637] app_dial.c: – SIP/lcrtelecom1-00000007 is making progress passing it to SIP/820-00000006
[Sep 6 21:38:39] VERBOSE[3976][C-00001637] app_dial.c: – SIP/lcrtelecom1-00000007 is making progress passing it to SIP/820-00000006
[Sep 6 21:38:40] VERBOSE[3976][C-00001637] app_dial.c: – SIP/lcrtelecom1-00000007 answered SIP/820-00000006
[Sep 6 21:38:40] VERBOSE[3976][C-00001637] rtp_engine.c: – Locally bridging SIP/820-00000006 and SIP/lcrtelecom1-00000007

This is an excerpt from logger,conf:

;debug => debug
;trace => trace
;security => security
console => notice,warning,error
;console => notice,warning,error,debug
messages => notice,warning,error
;full => notice,warning,error,debug,verbose,dtmf,fax
full => notice,warning,error,verbose(2),dtmf

What am I doing wrong?

Cheers,

BC

voip.com.vn · September 7, 2022, 3:47am

try to add debug to full=> …

bcnx · September 7, 2022, 12:27pm

I did, to no avail …

david551 · September 7, 2022, 12:33pm

Some or all of those messages are at verbose level 3, e.g.

github.com

asterisk/asterisk/blob/master/apps/app_dial.c#L1577


      
          							set_duration_var(in, "RINGTIME", diff);
          
          
							ast_channel_stage_snapshot_done(in);
          							ast_channel_unlock(in);
          							sent_ring = 1;
          						}
          					}
          					ast_channel_publish_dial(in, c, NULL, "RINGING");
          					break;
          				case AST_CONTROL_PROGRESS:
          					ast_verb(3, "%s is making progress passing it to %s\n", ast_channel_name(c), ast_channel_name(in));
          					/* Setup early media if appropriate */
          					if (single && !caller_entertained
          						&& CAN_EARLY_BRIDGE(peerflags, in, c)) {
          						ast_channel_early_bridge(in, c);
          					}
          					if (!ast_test_flag64(outgoing, OPT_RINGBACK)) {
          						if (single || (!single && !pa->sentringing)) {
          							ast_indicate(in, AST_CONTROL_PROGRESS);
          						}
          					}

but you’ve limited your full log to level 2.

debug setting have no relevance to the messages in question, which are clearly verbose ones.

bcnx · September 7, 2022, 12:49pm

Hi,

so I need something like this?

full => notice,warning,error,verbose(3),dtmf

So weird, I don’t have that on my other installs,

cheers,

BC

bcnx · September 7, 2022, 12:56pm

So weird, I did a logger rotate and now I see the needed VERBOSE messages.

Bug?

BC

david551 · September 7, 2022, 12:59pm

DId you do a reload for logger when after you changed the file. Also, if you remove the log file and create a new one, the file is only removed form the directory, but the file continues to exist, anonymously, until you do a rotate.

david551 · September 7, 2022, 1:01pm

If you don’t specify a level, the level set of the console will be used. That might be at least 3 on your other systems.

bcnx · September 7, 2022, 1:03pm

Yes, that must be it! The console is now started with “asterisk -rvvv”.

Is this then correct voor logger.conf ?:

full => notice,warning,error,verbose(3),dtmf

Thanks a mille!

BC

david551 · September 7, 2022, 1:22pm

That’s OK if you want the file locked at a limit of verbosity 3.

bcnx · September 7, 2022, 1:27pm

Thanks for your input, David!

BC

system · October 7, 2022, 1:28pm

This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.

Topic		Replies	Views
How to see sip-log and dialplan-log? Asterisk Support	1	220	October 1, 2007
Please help! Asterisk General	3	188	March 20, 2010
Asterisk log description - attributes Asterisk Support	8	4480	November 20, 2017
Toll-free not in service Asterisk Support	3	708	May 26, 2016
[SIP] Someone make call without auth Asterisk Support	2	579	August 24, 2013

Dial commands not being logged

Related topics