[asterisk-bugs] [JIRA] (ASTERISK-26749) apps/chan_dahdi.c has a method my_dahdi_write(), which returns zero, even if it fails.

Asterisk Team (JIRA) noreply at issues.asterisk.org
Wed Jan 25 04:46:24 CST 2017


    [ https://issues.asterisk.org/jira/browse/ASTERISK-26749?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=234859#comment-234859 ] 

Asterisk Team commented on ASTERISK-26749:
------------------------------------------

Thanks for creating a report! The issue has entered the triage process. That means the issue will wait in this status until a Bug Marshal has an opportunity to review the issue. Once the issue has been reviewed you will receive comments regarding the next steps towards resolution.

A good first step is for you to review the [Asterisk Issue Guidelines|https://wiki.asterisk.org/wiki/display/AST/Asterisk+Issue+Guidelines] if you haven't already. The guidelines detail what is expected from an Asterisk issue report.

Then, if you are submitting a patch, please review the [Patch Contribution Process|https://wiki.asterisk.org/wiki/display/AST/Patch+Contribution+Process].

> apps/chan_dahdi.c has a method my_dahdi_write(), which returns zero, even if it fails. 
> ---------------------------------------------------------------------------------------
>
>                 Key: ASTERISK-26749
>                 URL: https://issues.asterisk.org/jira/browse/ASTERISK-26749
>             Project: Asterisk
>          Issue Type: Bug
>      Security Level: None
>          Components: Channels/chan_dahdi
>         Environment: Gentoo Linux, asterisk 1.8.31.1
>            Reporter: Segii Cherkavskyi
>
> Although my asterisk version is not the newest, I checked chan_dahdi.c in the most recent asterisk version (13) and the code in question is remained untouched, so here is the problem: 
> apps/chan_dahdi.c has a method my_dahdi_write(), which returns zero, even if it fails. 
> The return value of the method my_dahdi_write() is checked in dahdi_write() and returns with -1, in case if my_dahdi_write() fails, which never happens.
> While searching through the asterisk issue tracker, I found similar logs to the one I have, with discussions about echo cancellation etc. not directly discussing the return value of my_dahdi_write(). Below is the log I have: 
> [Dec 15 10:02:55] DEBUG[5621] pbx.c: Launching 'Verbose'
> [Dec 15 10:02:55] VERBOSE[5621] pbx.c: [Dec 15 10:02:55]     -- Executing [fax at fax2local:1] Verbose("Local/1 at dahdifax-00000003;1", "1,### Local fax delivery from file /sirrix.fax/files/deliver/delivery_report-2xL1kJc0.tif.") in new stack
> [Dec 15 10:02:55] VERBOSE[5621] app_verbose.c: [Dec 15 10:02:55]  ### Local fax delivery from file /sirrix.fax/files/deliver/delivery_report-2xL1kJc0.tif.
> [Dec 15 10:02:55] DEBUG[5621] pbx.c: Launching 'System'
> [Dec 15 10:02:55] VERBOSE[5621] pbx.c: [Dec 15 10:02:55]     -- Executing [fax at fax2local:2] System("Local/1 at dahdifax-00000003;1", "/usr/bin/lcdisplay -s "Local fax" "handling..."") in new stack
> [Dec 15 10:02:55] DEBUG[5624] features.c: Removing dialed interfaces datastore on DAHDI/4-1 since we're bridging
> [Dec 15 10:02:55] DEBUG[5624] chan_dahdi.c: Requested indication 20 on channel DAHDI/4-1
> [Dec 15 10:02:55] DEBUG[5624] channel.c: Planning to masquerade channel DAHDI/4-1 into the structure of Local/1 at dahdifax-00000003;1
> [Dec 15 10:02:55] DEBUG[5624] channel.c: Done planning to masquerade channel DAHDI/4-1 into the structure of Local/1 at dahdifax-00000003;1
> [Dec 15 10:02:55] DEBUG[5528] channel.c: Set channel DAHDI/4-1 to write format slin
> [Dec 15 10:02:55] DEBUG[5528] channel.c: Set channel DAHDI/4-1 to read format slin
> [Dec 15 10:02:55] DEBUG[5528] channel.c: Putting channel DAHDI/4-1 in slin/slin formats
> [Dec 15 10:02:55] DEBUG[5528] chan_dahdi.c: New owner for channel 4 is DAHDI/4-1
> [Dec 15 10:02:55] DEBUG[5528] chan_dahdi.c: Updated conferencing on 4, with 0 conference users
> [Dec 15 10:02:55] DEBUG[5528] sig_analog.c: New owner for channel 4 is DAHDI/4-1
> [Dec 15 10:02:55] DEBUG[5528] sig_analog.c: Updated conferencing on 4, with 0 conference users
> [Dec 15 10:02:55] DEBUG[5528] chan_dahdi.c: Updated conferencing on 4, with 0 conference users
> [Dec 15 10:02:55] DEBUG[5528] channel.c: Done Masquerading DAHDI/4-1 (6)
> [Dec 15 10:02:55] DEBUG[5528] chan_dahdi.c: Requested indication 26 on channel DAHDI/4-1
> [Dec 15 10:02:55] DEBUG[5624] chan_local.c: Not posting to 'Local/1 at dahdifax-00000003;2' queue since already masqueraded out
> [Dec 15 10:02:55] DEBUG[5624] channel.c: Bridge stops because we're zombie or need a soft hangup: c0=Local/1 at dahdifax-00000003;2, c1=Local/1 at dahdifax-00000003;1<ZOMBIE>, flags: No,Yes,Yes,Yes
> [Dec 15 10:02:55] DEBUG[5624] channel.c: Bridge stops bridging channels Local/1 at dahdifax-00000003;2 and Local/1 at dahdifax-00000003;1<ZOMBIE>
> [Dec 15 10:02:55] DEBUG[5624] channel.c: Hanging up zombie 'Local/1 at dahdifax-00000003;1<ZOMBIE>'
> [Dec 15 10:02:55] DEBUG[5624] app_dial.c: Exiting with DIALSTATUS=ANSWER.
> [Dec 15 10:02:55] DEBUG[5624] pbx.c: Spawn extension (dahdifax,1,1) exited non-zero on 'Local/1 at dahdifax-00000003;2'
> [Dec 15 10:02:55] VERBOSE[5624] pbx.c: [Dec 15 10:02:55]   == Spawn extension (dahdifax, 1, 1) exited non-zero on 'Local/1 at dahdifax-00000003;2'
> [Dec 15 10:02:55] DEBUG[5624] channel.c: Soft-Hanging up channel 'Local/1 at dahdifax-00000003;2'
> [Dec 15 10:02:55] DEBUG[5624] channel.c: Hanging up channel 'Local/1 at dahdifax-00000003;2'
> [Dec 15 10:02:55] DEBUG[5621] pbx.c: Launching 'System'
> [Dec 15 10:02:55] VERBOSE[5621] pbx.c: [Dec 15 10:02:55]     -- Executing [fax at fax2local:3] System("DAHDI/4-1", "echo 2 > /tmp/localfax_out") in new stack
> [Dec 15 10:02:55] DEBUG[5621] pbx.c: Launching 'Set'
> [Dec 15 10:02:55] VERBOSE[5621] pbx.c: [Dec 15 10:02:55]     -- Executing [fax at fax2local:4] Set("DAHDI/4-1", "LOCALSTATIONID=R&S CS FaxEncryption") in new stack
> [Dec 15 10:02:55] DEBUG[5621] pbx.c: Launching 'SendFAX'
> [Dec 15 10:02:55] VERBOSE[5621] pbx.c: [Dec 15 10:02:55]     -- Executing [fax at fax2local:5] SendFAX("DAHDI/4-1", "/sirrix.fax/files/deliver/delivery_report-2xL1kJc0.tif") in new stack
> [Dec 15 10:02:55] DEBUG[5621] chan_dahdi.c: Reporting digit detection enabled on DAHDI/4-1
> [Dec 15 10:02:55] DEBUG[5621] chan_dahdi.c: Disabling digit detection on DAHDI/4-1
> [Dec 15 10:02:55] DEBUG[5621] chan_dahdi.c: Disabling fax tone detection on DAHDI/4-1
> [Dec 15 10:02:55] DEBUG[5621] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
> [Dec 15 10:02:56] DEBUG[5518] chan_dahdi.c: Monitor doohicky got event Hook Transition Complete on channel 1
> [Dec 15 10:02:56] DEBUG[5518] sig_analog.c: channel (1) - signaling (2) - event (ANALOG_EVENT_HOOKCOMPLETE)
> [Dec 15 10:02:56] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:02:57] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:02:57] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:02:57] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:02:57] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 2 (index 0)
> [Dec 15 10:02:57] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:02:57] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:02:57] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:02:57] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:02:57] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 3 (index 0)
> [Dec 15 10:02:59] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:02:59] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:02:59] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:02:59] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 2 (index 0)
> [Dec 15 10:02:59] VERBOSE[5623] app_dial.c: [Dec 15 10:02:59]     -- DAHDI/2-1 is ringing
> [Dec 15 10:02:59] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:02:59] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:02:59] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:02:59] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:02:59] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 3 (index 0)
> [Dec 15 10:02:59] VERBOSE[5623] app_dial.c: [Dec 15 10:02:59]     -- DAHDI/3-1 is ringing
> [Dec 15 10:03:00] DEBUG[5621] chan_dahdi.c: Write returned -1 (Unknown error 500) on channel 4
> [Dec 15 10:03:00] DEBUG[5621] sig_analog.c: analog_exception 4
> [Dec 15 10:03:00] DEBUG[5621] sig_analog.c: Exception on 12, channel 4
> [Dec 15 10:03:00] DEBUG[5621] sig_analog.c: __analog_handle_event 4
> [Dec 15 10:03:00] DEBUG[5621] sig_analog.c: Got event ANALOG_EVENT_EC_NLP_DISABLED(19) on channel 4 (index 0)
> [Dec 15 10:03:00] VERBOSE[5621] sig_analog.c: [Dec 15 10:03:00]     -- Channel 4 echo canceler disabled its NLP.
> [Dec 15 10:03:00] DEBUG[5621] sig_analog.c: analog_exception 4
> [Dec 15 10:03:00] DEBUG[5621] sig_analog.c: Exception on 12, channel 4
> [Dec 15 10:03:00] DEBUG[5621] sig_analog.c: __analog_handle_event 4
> [Dec 15 10:03:00] DEBUG[5621] sig_analog.c: Got event ANALOG_EVENT_RX_CED_DETECTED(18) on channel 4 (index 0)
> [Dec 15 10:03:00] VERBOSE[5621] sig_analog.c: [Dec 15 10:03:00]     -- Channel 4 detected a CED tone from the network.
> [Dec 15 10:03:02] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:03] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:03] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:03] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:03] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:03] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 2 (index 0)
> [Dec 15 10:03:03] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:03] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:03] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:03] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 3 (index 0)
> [Dec 15 10:03:04] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:05] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:05] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:05] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:05] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 2 (index 0)
> [Dec 15 10:03:05] VERBOSE[5623] app_dial.c: [Dec 15 10:03:05]     -- DAHDI/2-1 is ringing
> [Dec 15 10:03:05] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:06] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:06] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:06] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:06] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 3 (index 0)
> [Dec 15 10:03:06] VERBOSE[5623] app_dial.c: [Dec 15 10:03:06]     -- DAHDI/3-1 is ringing
> [Dec 15 10:03:06] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:09] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:10] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:10] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:10] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:10] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 2 (index 0)
> [Dec 15 10:03:10] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:10] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:10] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:10] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 3 (index 0)
> [Dec 15 10:03:10] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:12] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:12] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:12] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:12] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 2 (index 0)
> [Dec 15 10:03:12] VERBOSE[5623] app_dial.c: [Dec 15 10:03:12]     -- DAHDI/2-1 is ringing
> [Dec 15 10:03:12] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:12] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:12] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:12] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:12] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 3 (index 0)
> [Dec 15 10:03:12] VERBOSE[5623] app_dial.c: [Dec 15 10:03:12]     -- DAHDI/3-1 is ringing
> [Dec 15 10:03:12] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:15] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:15] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:16] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:16] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:16] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:16] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 2 (index 0)
> [Dec 15 10:03:16] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:16] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:16] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:16] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 3 (index 0)
> [Dec 15 10:03:16] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:18] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:18] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:18] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:18] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 2 (index 0)
> [Dec 15 10:03:18] VERBOSE[5623] app_dial.c: [Dec 15 10:03:18]     -- DAHDI/2-1 is ringing
> [Dec 15 10:03:18] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:18] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:18] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:18] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:18] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 3 (index 0)
> [Dec 15 10:03:18] VERBOSE[5623] app_dial.c: [Dec 15 10:03:18]     -- DAHDI/3-1 is ringing
> [Dec 15 10:03:18] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:21] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:21] DEBUG[5521] pbx_spool.c: Filename: /var/spool/asterisk/outgoing/delivery_report-2xL1kJc0.tif.call, Retries: 0, max: 0
> [Dec 15 10:03:21] DEBUG[5521] pbx_spool.c: Outgoing local/1 at dahdifax: DelayedRetry
> [Dec 15 10:03:21] DEBUG[5521] pbx_spool.c: Delaying retry since we're currently running '/var/spool/asterisk/outgoing/delivery_report-2xL1kJc0.tif.call'
> [Dec 15 10:03:21] DEBUG[5521] pbx_spool.c: Filename: /var/spool/asterisk/outgoing/fax_with_header-KlznTSWG.tif.call, Retries: 0, max: 0
> [Dec 15 10:03:21] DEBUG[5521] pbx_spool.c: Outgoing local/1 at dahdifax: DelayedRetry
> [Dec 15 10:03:21] DEBUG[5521] pbx_spool.c: Delaying retry since we're currently running '/var/spool/asterisk/outgoing/fax_with_header-KlznTSWG.tif.call'
> [Dec 15 10:03:22] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:22] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:22] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:22] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 2 (index 0)
> [Dec 15 10:03:22] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:22] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:22] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:22] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:22] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 3 (index 0)
> [Dec 15 10:03:24] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:24] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:24] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:24] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 2 (index 0)
> [Dec 15 10:03:24] VERBOSE[5623] app_dial.c: [Dec 15 10:03:24]     -- DAHDI/2-1 is ringing
> [Dec 15 10:03:24] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:24] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:24] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:24] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:24] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 3 (index 0)
> [Dec 15 10:03:24] VERBOSE[5623] app_dial.c: [Dec 15 10:03:24]     -- DAHDI/3-1 is ringing
> [Dec 15 10:03:27] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:27] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:28] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:28] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:28] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:28] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 2 (index 0)
> [Dec 15 10:03:28] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:28] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:28] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:28] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 3 (index 0)
> [Dec 15 10:03:30] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:30] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:30] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:30] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 2 (index 0)
> [Dec 15 10:03:30] VERBOSE[5623] app_dial.c: [Dec 15 10:03:30]     -- DAHDI/2-1 is ringing
> [Dec 15 10:03:30] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:30] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:30] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:30] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:30] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 3 (index 0)
> [Dec 15 10:03:30] VERBOSE[5623] app_dial.c: [Dec 15 10:03:30]     -- DAHDI/3-1 is ringing
> [Dec 15 10:03:30] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:34] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:34] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:34] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:34] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 2 (index 0)
> [Dec 15 10:03:34] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:34] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:34] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:34] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:34] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGERON(7) on channel 3 (index 0)
> [Dec 15 10:03:35] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:36] DEBUG[5519] chan_sip.c: Allocating new SIP dialog for 52f4777f334b7e6e515ae6a739b5c13b at 134.147.62.165:5061 - OPTIONS (No RTP)
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: analog_exception 2
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: Exception on 10, channel 2
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: __analog_handle_event 2
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 2 (index 0)
> [Dec 15 10:03:36] VERBOSE[5623] app_dial.c: [Dec 15 10:03:36]     -- DAHDI/2-1 is ringing
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: analog_exception 3
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: Exception on 11, channel 3
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: __analog_handle_event 3
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: Got event ANALOG_EVENT_RINGEROFF(8) on channel 3 (index 0)
> [Dec 15 10:03:36] VERBOSE[5623] app_dial.c: [Dec 15 10:03:36]     -- DAHDI/3-1 is ringing
> [Dec 15 10:03:36] DEBUG[5622] channel.c: Hanging up channel 'Local/1 at dahdifax-00000002;1'
> [Dec 15 10:03:36] DEBUG[5623] channel.c: Hanging up channel 'DAHDI/3-1'
> [Dec 15 10:03:36] DEBUG[5623] chan_dahdi.c: dahdi_hangup(DAHDI/3-1)
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: analog_hangup 3
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: Hangup: channel: 3 index = 0, normal = 1, callwait = 0, thirdcall = 0
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'Verbose'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [failed at fax2local:1] Verbose("OutgoingSpoolFailed", "1,### Local fax delivery from file /sirrix.fax/files/deliver/fax_with_header-KlznTSWG.tif failed - no answer from fax") in new stack
> [Dec 15 10:03:36] VERBOSE[5622] app_verbose.c: [Dec 15 10:03:36]  ### Local fax delivery from file /sirrix.fax/files/deliver/fax_with_header-KlznTSWG.tif failed - no answer from fax
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'Set'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [failed at fax2local:2] Set("OutgoingSpoolFailed", "LOCALFAXFAILED="TRUE"") in new stack
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'System'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [failed at fax2local:3] System("OutgoingSpoolFailed", "/usr/bin/lcdisplay -s "Local fax" "check failure!" && beep") in new stack
> [Dec 15 10:03:36] DEBUG[5623] chan_dahdi.c: Set option TONE VERIFY, mode: OFF(0) on DAHDI/3-1
> [Dec 15 10:03:36] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:36] DEBUG[5623] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/3-1
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: Updated conferencing on 3, with 0 conference users
> [Dec 15 10:03:36] VERBOSE[5623] sig_analog.c: [Dec 15 10:03:36]     -- Hanging up on 'DAHDI/3-1'
> [Dec 15 10:03:36] VERBOSE[5623] chan_dahdi.c: [Dec 15 10:03:36]     -- Hungup 'DAHDI/3-1'
> [Dec 15 10:03:36] DEBUG[5623] channel.c: Hanging up channel 'DAHDI/2-1'
> [Dec 15 10:03:36] DEBUG[5623] chan_dahdi.c: dahdi_hangup(DAHDI/2-1)
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: analog_hangup 2
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: Hangup: channel: 2 index = 0, normal = 1, callwait = 0, thirdcall = 0
> [Dec 15 10:03:36] DEBUG[5623] chan_dahdi.c: Set option TONE VERIFY, mode: OFF(0) on DAHDI/2-1
> [Dec 15 10:03:36] DEBUG[5623] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/2-1
> [Dec 15 10:03:36] DEBUG[5623] sig_analog.c: Updated conferencing on 2, with 0 conference users
> [Dec 15 10:03:36] VERBOSE[5623] sig_analog.c: [Dec 15 10:03:36]     -- Hanging up on 'DAHDI/2-1'
> [Dec 15 10:03:36] VERBOSE[5623] chan_dahdi.c: [Dec 15 10:03:36]     -- Hungup 'DAHDI/2-1'
> [Dec 15 10:03:36] DEBUG[5623] app_dial.c: Exiting with DIALSTATUS=CANCEL.
> [Dec 15 10:03:36] DEBUG[5623] pbx.c: Spawn extension (dahdifax,1,1) exited non-zero on 'Local/1 at dahdifax-00000002;2'
> [Dec 15 10:03:36] VERBOSE[5623] pbx.c: [Dec 15 10:03:36]   == Spawn extension (dahdifax, 1, 1) exited non-zero on 'Local/1 at dahdifax-00000002;2'
> [Dec 15 10:03:36] DEBUG[5623] channel.c: Soft-Hanging up channel 'Local/1 at dahdifax-00000002;2'
> [Dec 15 10:03:36] DEBUG[5623] channel.c: Hanging up channel 'Local/1 at dahdifax-00000002;2'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Auto fallthrough, channel 'OutgoingSpoolFailed' status is 'UNKNOWN'
> [Dec 15 10:03:36] DEBUG[5622] channel.c: Soft-Hanging up channel 'OutgoingSpoolFailed'
> [Dec 15 10:03:36] WARNING[5622] ast_expr2.fl: ast_yyerror():  syntax error: syntax error, unexpected '<token>', expecting $end; Input:
> ""TRUE"" = "TRUE"
>   ^
> [Dec 15 10:03:36] WARNING[5622] ast_expr2.fl: If you have questions, please refer to https://wiki.asterisk.org/wiki/display/AST/Channel+Variables
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Expression result is '""'
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'GotoIf'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [h at fax2local:1] GotoIf("OutgoingSpoolFailed", """?failed") in new stack
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Goto (fax2local,h,4)
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'Verbose'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [h at fax2local:4] Verbose("OutgoingSpoolFailed", "1,### keeping fax file for retry") in new stack
> [Dec 15 10:03:36] VERBOSE[5622] app_verbose.c: [Dec 15 10:03:36]  ### keeping fax file for retry
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'System'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [h at fax2local:5] System("OutgoingSpoolFailed", "/sirrix.fax/scripts/delayed_copy.sh /sirrix.fax/files/callfiles/fax_with_header-KlznTSWG.tif.call /var/spool/asterisk/outgoing/ 30 &") in new stack
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'Goto'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [h at fax2local:6] Goto("OutgoingSpoolFailed", "end") in new stack
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Goto (fax2local,h,10)
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'Verbose'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [h at fax2local:10] Verbose("OutgoingSpoolFailed", "1,### finished handling /sirrix.fax/files/deliver/fax_with_header-KlznTSWG.tif)") in new stack
> [Dec 15 10:03:36] VERBOSE[5622] app_verbose.c: [Dec 15 10:03:36]  ### finished handling /sirrix.fax/files/deliver/fax_with_header-KlznTSWG.tif)
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'System'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [h at fax2local:11] System("OutgoingSpoolFailed", "/usr/bin/lcdisplay -s "Local fax" "done handling" ") in new stack
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'System'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [h at fax2local:12] System("OutgoingSpoolFailed", "rm -f /tmp/localfax_out") in new stack
> [Dec 15 10:03:36] DEBUG[5622] pbx.c: Launching 'System'
> [Dec 15 10:03:36] VERBOSE[5622] pbx.c: [Dec 15 10:03:36]     -- Executing [h at fax2local:13] System("OutgoingSpoolFailed", "/sirrix.fax/scripts/idle_display.sh") in new stack
> [Dec 15 10:03:37] DEBUG[5518] chan_dahdi.c: Monitor doohicky got event Hook Transition Complete on channel 3
> [Dec 15 10:03:37] DEBUG[5518] sig_analog.c: channel (3) - signaling (2) - event (ANALOG_EVENT_HOOKCOMPLETE)
> [Dec 15 10:03:37] DEBUG[5518] chan_dahdi.c: Monitor doohicky got event Hook Transition Complete on channel 2
> [Dec 15 10:03:37] DEBUG[5518] sig_analog.c: channel (2) - signaling (2) - event (ANALOG_EVENT_HOOKCOMPLETE)
> [Dec 15 10:03:37] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:39] DEBUG[5621] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel 4
> [Dec 15 10:03:39] DEBUG[5622] channel.c: Hanging up channel 'OutgoingSpoolFailed'
> [Dec 15 10:03:39] NOTICE[5622] pbx_spool.c: Call failed to go through, reason (3) Remote end Ringing
> [Dec 15 10:03:39] NOTICE[5622] pbx_spool.c: Queued call to local/1 at dahdifax expired without completion after 0 attempts
> [Dec 15 10:03:50] DEBUG[5517] chan_sip.c: Allocating new SIP dialog for 1e78b85f48a6d95046c40ce61203724e at 172.25.31.159:5060 - OPTIONS (No RTP)
> [Dec 15 10:03:50] DEBUG[5517] chan_sip.c: SIP call-id changed from '1e78b85f48a6d95046c40ce61203724e at 172.25.31.159:5060' to '32992bc12c2e681769eccdb973d5536a at 172.25.31.159:5061'
> [Dec 15 10:03:50] DEBUG[5517] chan_sip.c: Initializing initreq for method OPTIONS - callid 32992bc12c2e681769eccdb973d5536a at 172.25.31.159:5061
> [Dec 15 10:03:51] DEBUG[5521] pbx_spool.c: Filename: /var/spool/asterisk/outgoing/delivery_report-2xL1kJc0.tif.call, Retries: 0, max: 0
> [Dec 15 10:03:51] DEBUG[5521] pbx_spool.c: Outgoing local/1 at dahdifax: DelayedRetry
> [Dec 15 10:03:51] DEBUG[5521] pbx_spool.c: Delaying retry since we're currently running '/var/spool/asterisk/outgoing/delivery_report-2xL1kJc0.tif.call'
> [Dec 15 10:03:57] DEBUG[5621] app_fax.c: Fax phase E handler. result=0
> [Dec 15 10:03:57] DEBUG[5621] app_fax.c: Fax transmitted successfully.
> [Dec 15 10:03:57] DEBUG[5621] app_fax.c:   Remote station ID: 
> [Dec 15 10:03:57] DEBUG[5621] app_fax.c:   Pages transferred: 1
> [Dec 15 10:03:57] DEBUG[5621] app_fax.c:   Image resolution:  8031 x 7700
> [Dec 15 10:03:57] DEBUG[5621] app_fax.c:   Transfer Rate:     12000
> [Dec 15 10:03:57] DEBUG[5621] app_fax.c: Loop finished, res=0
> [Dec 15 10:03:57] DEBUG[5621] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
> [Dec 15 10:03:57] DEBUG[5621] app_fax.c: Transmission finished Ok
> [Dec 15 10:03:57] DEBUG[5621] chan_dahdi.c: Enabling digit detection on DAHDI/4-1
> [Dec 15 10:03:57] DEBUG[5621] pbx.c: Launching 'Hangup'
> [Dec 15 10:03:57] VERBOSE[5621] pbx.c: [Dec 15 10:03:57]     -- Executing [fax at fax2local:6] Hangup("DAHDI/4-1", "") in new stack
> [Dec 15 10:03:57] DEBUG[5621] pbx.c: Spawn extension (fax2local,fax,6) exited non-zero on 'DAHDI/4-1'
> [Dec 15 10:03:57] VERBOSE[5621] pbx.c: [Dec 15 10:03:57]   == Spawn extension (fax2local, fax, 6) exited non-zero on 'DAHDI/4-1'
> [Dec 15 10:03:57] DEBUG[5621] channel.c: Soft-Hanging up channel 'DAHDI/4-1'
> [Dec 15 10:03:57] DEBUG[5621] pbx.c: Expression result is '0'



--
This message was sent by Atlassian JIRA
(v6.2#6252)



More information about the asterisk-bugs mailing list