vicidial.org

Guys,

Why is appearing this ERROR on CLI, REDUX 0.1.6?...

Connected to Asterisk 1.4.28 currently running on dialer1 (pid = 3536)
Verbosity was 0 and is now 27
[May 14 11:13:50] -- Remote UNIX connection
[May 14 11:14:01] == Parsing '/etc/asterisk/manager.conf': [May 14 11:14:01] Found
[May 14 11:14:01] == Manager 'sendcron' logged on from 127.0.0.1
[May 14 11:14:01] ERROR[11344]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 14 11:14:01] ERROR[11344]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 14 11:14:01] == Manager 'sendcron' logged off from 127.0.0.1
[May 14 11:14:01] == Parsing '/etc/asterisk/manager.conf': [May 14 11:14:01] Found
[May 14 11:14:01] == Manager 'sendcron' logged on from 127.0.0.1
[May 14 11:14:06] == Parsing '/etc/asterisk/manager.conf': [May 14 11:14:06] Found
[May 14 11:14:06] == Manager 'sendcron' logged on from 127.0.0.1
[May 14 11:14:06] ERROR[11357]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 14 11:14:06] ERROR[11357]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 14 11:14:06] == Manager 'sendcron' logged off from 127.0.0.1
[May 14 11:14:06] ERROR[11348]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 14 11:14:06] == Manager 'sendcron' logged off from 127.0.0.1
[May 14 11:15:01] == Parsing '/etc/asterisk/manager.conf': [May 14 11:15:01] Found
[May 14 11:15:01] == Manager 'sendcron' logged on from 127.0.0.1
[May 14 11:15:01] == Parsing '/etc/asterisk/manager.conf': [May 14 11:15:01] Found
[May 14 11:15:01] == Manager 'sendcron' logged on from 127.0.0.1
[May 14 11:15:01] ERROR[11459]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 14 11:15:01] ERROR[11459]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 14 11:15:01] == Manager 'sendcron' logged off from 127.0.0.1
[May 14 11:15:06] == Parsing '/etc/asterisk/manager.conf': [May 14 11:15:06] Found
[May 14 11:15:06] == Manager 'sendcron' logged on from 127.0.0.1
[May 14 11:15:06] ERROR[11472]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 14 11:15:06] ERROR[11472]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 14 11:15:06] == Manager 'sendcron' logged off from 127.0.0.1
[May 14 11:15:07] == Manager 'sendcron' logged off from 127.0.0.1
dialer1*CLI>

It's normal, not sure why the Asterisk maintainers decided to add this as an ERROR since it is not really an error, but you can safely ignore it.

Matt,

This is appearing all the time...is there some way to avoid it?....

Thanks.

install asterisk 1.4.21.2

All this message is reflecting is that a process has connected to the manager interface, then disconnected without doing anything (closing the pipe). This is not a real error or warning message. At the most it should have been considered a notice.

Guys,

Do you think that is convenient to downgrade my Vicibox installs to Asterisk 1.4.21.2 due to this "ast_carefulwrite" message, in a production environment, or should I avoid it and let the message appear all the time on CLi?...

I'm using DAHDI so I really dont like the idea to go back to 1.4.21

Will this [BUG],[MISTAKE] be patched on future?...

Thanks.

How frequent is this message?

It usually does not affect anything and is not a reason for a downgrade.

This message appears every 55-60 secs :

Take a look:

Code: Select all

[May 18 12:25:01] ERROR[4157]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:25:01] ERROR[4157]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:25:01]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:25:01]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:25:01] Found
[May 18 12:25:01]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:25:06]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:25:06] Found
[May 18 12:25:06]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:25:06] ERROR[4169]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:25:06] ERROR[4169]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:25:06]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:25:06] ERROR[4159]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:25:06]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:26:01]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:26:01] Found
[May 18 12:26:01]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:26:01] ERROR[4247]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:26:01] ERROR[4247]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:26:01]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:26:01]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:26:01] Found
[May 18 12:26:01]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:26:06]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:26:06] Found
[May 18 12:26:06]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:26:06] ERROR[4264]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:26:06] ERROR[4264]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:26:06]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:26:07]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:27:01]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:27:01] Found
[May 18 12:27:01]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:27:02]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:27:02]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:27:02] Found
[May 18 12:27:02]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:27:07]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:27:07] Found
[May 18 12:27:07]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:27:07] ERROR[4360]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:27:07] ERROR[4360]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:27:07]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:27:07]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:28:01]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:28:01] Found
[May 18 12:28:01]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:28:01] ERROR[4436]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:28:01] ERROR[4436]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:28:01]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:28:01]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:28:01] Found
[May 18 12:28:01]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:28:06]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:28:06] Found
[May 18 12:28:06]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:28:06] ERROR[4456]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:28:06] ERROR[4456]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:28:06]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:28:06] ERROR[4439]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:28:06]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:29:01]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:29:01] Found
[May 18 12:29:01]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:29:01] ERROR[4534]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:29:01] ERROR[4534]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:29:01]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:29:01]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:29:01] Found
[May 18 12:29:01]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:29:06]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:29:06] Found
[May 18 12:29:06]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:29:06] ERROR[4548]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:29:06] ERROR[4548]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:29:06]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:29:06] ERROR[4536]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:29:06]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:30:02]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:30:02] Found
[May 18 12:30:02]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:30:02]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:30:02] Found
[May 18 12:30:02]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:30:02] ERROR[4648]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:30:02] ERROR[4648]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:30:02]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:30:07]   == Parsing '/etc/asterisk/manager.conf': [May 18 12:30:07] Found
[May 18 12:30:07]   == Manager 'sendcron' logged on from 127.0.0.1
[May 18 12:30:07] ERROR[4668]: utils.c:966 ast_carefulwrite: write() returned error: Connection reset by peer
[May 18 12:30:07] ERROR[4668]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:30:07]   == Manager 'sendcron' logged off from 127.0.0.1
[May 18 12:30:07] ERROR[4653]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[May 18 12:30:07]   == Manager 'sendcron' logged off from 127.0.0.1


Yes, unfortunately that is normal and it does not affect anything, it should not be considered a true ERROR by Asterisk so I'm not sure why they made that change in 1.4.24.

Matt,

This [MESSAGE] appears even on version 1.4.22.

On version 1.4.21 it doesn't appears.

Thanks for the note, 1.4.22 and 1.4.23 have some horrible bugs in them so I never bothered testing them for any length of time, the first time I noticed this issue was on 1.4.24

Matt,

I was doing some testing and I get this [MESSAGE] filling the whole CLi only when I set separate DB SERVER for VICIDIAL.

If I run DB SERVER locally this "utils.c:966 ast_carefulwrite" message gone!

May you know why could this be?....

Regards.

We are looking into how to fix this, but since it is not a critical bug it has not been a priority.

nano +966 /usr/src/asterisk-1.4.27.1-vici/main/utils.c

Code: Select all

                if (res < 0 && errno != EAGAIN && errno != EINTR) {
                        /* fatal error from write() */
                        ast_log(LOG_ERROR, "write() returned error: %s\n", strerror(errno));
                        return -1;
                }



change to:

Code: Select all

                if (res < 0 && errno != EAGAIN && errno != EINTR) {
                        /* fatal error from write() */
                        ast_log(LOG_ERROR, "write() returned error[%d]: %s\n",errno,strerror(errno));
                        return -1;
                }



recompile and run it again,

this will print both the error number and the error string

then change by adding && errno != X (the error number) to the list of "not included" error numbers.

recompile and run it AGAIN

this isn't pretty (as opposed to modifying the Logic), but it should kill THIS particular error listing, enough to stop it from annoying people.

@williamconley

can this apply also to asterisk 1.4.30-vici. Right..?

nano that same file (and search for those lines). It may or may not be identical.