[asterisk-bugs] [JIRA] (ASTERISK-26749) chan_dahdi.c: my_dahdi_write() returns zero even if it fails.
Segii Cherkavskyi (JIRA)
noreply at issues.asterisk.org
Wed Jan 25 12:56:10 CST 2017
[ https://issues.asterisk.org/jira/browse/ASTERISK-26749?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=234870#comment-234870 ]
Segii Cherkavskyi commented on ASTERISK-26749:
----------------------------------------------
Hello Richard
Thank you and the whole team for helping with the issue. I apologise for badly created ticket, I will improve.
In my use case multiple faxes are being queued by means of callfiles for a delivery to a fax machine. While there are 4 dahdi channels, only one is able to answer, since only one of four ports is occupied by a fax machine.
>From the log you can see that when channel 4 is answered, dahdi is unable to write data to a file descriptor in any of the calls to dahdi_write(). So the frames queued by SendFax() are silently droped. At the very end of the log however, once SendFax() application is done with queuing frames to dahdi, I see that transmission was successful (app_fax.c lines) and hangs up the channel resulting in dahdi channel also being hung up. If I correctly interpret the log, then behavior of dahdi is wrong and the correct would be to hang up the channel.
I look forward to receiving your comment and thanks once again for help!
> chan_dahdi.c: my_dahdi_write() 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
> Assignee: 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