FS 1.8 code release broke windows compile
hi, i was using VS2017 with SDK10 on master and it was building just fine but today FS1.8 code released to master and that broke compile on windows using VS2017 and SDK10, FreeSwitchConsole.exe doesnt build even though the rest of it compiled just fine and also mod_av used to build find on older master that totally broke but i can live without it. -- Regards, Bipin ------------------------------------------------------------------------
Give it a try again. We have building clean now. Couple bumps in the merge with some other patches. You may need to fresh checkout.
On Jul 24, 2018, at 6:06 AM, Bipin Patel <bipin@xbipin.com> wrote:
hi,
i was using VS2017 with SDK10 on master and it was building just fine but today FS1.8 code released to master and that broke compile on windows using VS2017 and SDK10, FreeSwitchConsole.exe doesnt build even though the rest of it compiled just fine and also mod_av used to build find on older master that totally broke but i can live without it.
-- Regards, Bipin
Would it be possible to upload an updated build to files.freeswitch.org? Thanks On Tue, Jul 24, 2018 at 12:12 PM, Michael Jerris <mike@jerris.com> wrote:
Give it a try again. We have building clean now. Couple bumps in the merge with some other patches. You may need to fresh checkout.
On Jul 24, 2018, at 6:06 AM, Bipin Patel <bipin@xbipin.com> wrote:
hi,
i was using VS2017 with SDK10 on master and it was building just fine but today FS1.8 code released to master and that broke compile on windows using VS2017 and SDK10, FreeSwitchConsole.exe doesnt build even though the rest of it compiled just fine and also mod_av used to build find on older master that totally broke but i can live without it.
-- Regards, Bipin
_________________________________________________________________________ Professional FreeSWITCH Services sales@freeswitch.com https://freeswitch.com
Official FreeSWITCH Sites https://freeswitch.com/oss https://freeswitch.org/confluence https://cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users https://freeswitch.com
It already is. http://files.freeswitch.org/windows/installer/x64/FreeSWITCH-1.8.1-x64-Relea... <http://files.freeswitch.org/windows/installer/x64/FreeSWITCH-1.8.1-x64-Release.msi>
On Jul 24, 2018, at 1:37 PM, Abaci B <abaci64@gmail.com> wrote:
Would it be possible to upload an updated build to files.freeswitch.org <http://files.freeswitch.org/>? Thanks
On Tue, Jul 24, 2018 at 12:12 PM, Michael Jerris <mike@jerris.com <mailto:mike@jerris.com>> wrote: Give it a try again. We have building clean now. Couple bumps in the merge with some other patches. You may need to fresh checkout.
On Jul 24, 2018, at 6:06 AM, Bipin Patel <bipin@xbipin.com <mailto:bipin@xbipin.com>> wrote:
hi,
i was using VS2017 with SDK10 on master and it was building just fine but today FS1.8 code released to master and that broke compile on windows using VS2017 and SDK10, FreeSwitchConsole.exe doesnt build even though the rest of it compiled just fine and also mod_av used to build find on older master that totally broke but i can live without it.
-- Regards, Bipin
I also think I spoke too soon on that being in the msi and there does seem to be a build issue. Looking into it.
On Jul 30, 2018, at 1:15 PM, Michael Jerris <mike@jerris.com> wrote:
It already is.
http://files.freeswitch.org/windows/installer/x64/FreeSWITCH-1.8.1-x64-Relea... <http://files.freeswitch.org/windows/installer/x64/FreeSWITCH-1.8.1-x64-Release.msi>
On Jul 24, 2018, at 1:37 PM, Abaci B <abaci64@gmail.com <mailto:abaci64@gmail.com>> wrote:
Would it be possible to upload an updated build to files.freeswitch.org <http://files.freeswitch.org/>? Thanks
On Tue, Jul 24, 2018 at 12:12 PM, Michael Jerris <mike@jerris.com <mailto:mike@jerris.com>> wrote: Give it a try again. We have building clean now. Couple bumps in the merge with some other patches. You may need to fresh checkout.
On Jul 24, 2018, at 6:06 AM, Bipin Patel <bipin@xbipin.com <mailto:bipin@xbipin.com>> wrote:
hi,
i was using VS2017 with SDK10 on master and it was building just fine but today FS1.8 code released to master and that broke compile on windows using VS2017 and SDK10, FreeSwitchConsole.exe doesnt build even though the rest of it compiled just fine and also mod_av used to build find on older master that totally broke but i can live without it.
-- Regards, Bipin
Yes I tried after the 2 patches to the switch_utils.h file and now it compiles just fine. Although lib and mod_av don't which they used to on the older master On July 24, 2018 8:24:24 PM Michael Jerris <mike@jerris.com> wrote:
Give it a try again. We have building clean now. Couple bumps in the merge with some other patches. You may need to fresh checkout.
On Jul 24, 2018, at 6:06 AM, Bipin Patel <bipin@xbipin.com> wrote:
hi,
i was using VS2017 with SDK10 on master and it was building just fine but today FS1.8 code released to master and that broke compile on windows using VS2017 and SDK10, FreeSwitchConsole.exe doesnt build even though the rest of it compiled just fine and also mod_av used to build find on older master that totally broke but i can live without it.
-- Regards, Bipin
_________________________________________________________________________ Professional FreeSWITCH Services sales@freeswitch.com https://freeswitch.com
Official FreeSWITCH Sites https://freeswitch.com/oss https://freeswitch.org/confluence https://cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users https://freeswitch.com
Hi All, We've experienced a number of segmentation faults over time with various errors in the core dump and no clear indication of the exact cause of the problem, we suspect this is load related. FreeSWITCH is typically handling more than 100 concurrent sessions but rarely more than 10 session per second, these segmentation faults have even occurred in the early hours of the morning with less than 10 session at the time which leads us to believe the bottleneck is on the number of simultaneous registrations\subscriptions which is the only variable which is roughly the same at all hours only decreasing and increasing slightly in the evenings and mornings. We've created more SIP profiles to distribute registrations to no avail, we're only able to prevent segmentation faults entirely by distributing registrations across more servers. The problems seem to occur when we have combination of: - 10,000 subscriptions and 400 registrations; reducing subscriptions to 5000 resolves the issue. - 1200 subscriptions and 950 registrations; reducing registrations to 700 resolves the issue. We have a few FreeSWITCH servers which are dedicated for call routing only, these are processing in excess of 400 concurrent sessions and never have any issues. Our server specs: Debian 8.9 PostgreSQL 9.4 - Optimised for best performance FreeSWITCH 1.6.20 - Using PostgreSQL backend 32GB RAM Dual socket Intel Xeon X5670 2.93Ghz - With Hyper threading enabled Our plan is to implement SBCs in the future to handle subscriptions and registrations but in the short term we need to understand the limitations of FreeSWITCH, where are the bottlenecks, and are there any other solutions. Looking at SIP traces at the time of these segmentation faults there are more than 30 SIP messages per second mostly subscribe, 202, notify, registration and 200. If we look at all packets on the FreeSWITCH SIP port including Application Data, ACK etc there are hundreds of packets per second. We initially suspected the bottleneck could be the FreeSWITCH backend database but moving to PostgreSQL has had no effect. Has anyone come across similar issues before and\or is able to offer any advice? We're currently looking at two possibilities, a software bottleneck with-in FreeSWITCH, or a bottleneck on the hardware possibly as a result of using dual socket CPUs. We are in the process of enabling node interleaving on the hardware so we use SUMA (Sufficiently Uniform Memory Access) instead of NUMA (Non-Uniform Memory Access) which should theoretically reduce traffic on the QPI bridge slightly given that FreeSWITCH isn't optimised for NUMA. Here's an example of some of some of the segmentation faults we've experienced: Program terminated with signal SIGSEGV, Segmentation fault. #0 strlen () at ../sysdeps/x86_64/strlen.S:106 106 ../sysdeps/x86_64/strlen.S: No such file or directory. (gdb) bt #0 strlen () at ../sysdeps/x86_64/strlen.S:106 #1 0x00007f92302f697e in __GI___strdup (s=0xecd42c18 <error: Cannot access memory at address 0xecd42c18>) at strdup.c:41 #2 0x00007f9231849ea4 in switch_ivr_generate_xml_cdr (session=0x7f91dd8146a8, xml_cdr=0x7f900b6da748) at src/switch_ivr.c:2803 #3 0x00007f91696fc7b0 in my_on_reporting (session=0x7f91dd8146a8) at mod_xml_cdr.c:228 #4 0x00007f92317801d5 in switch_core_session_reporting_state (session=0x7f91dd8146a8) at src/switch_core_state_machine.c:938 #5 0x00007f923177bf9c in switch_core_session_run (session=0x7f91dd8146a8) at src/switch_core_state_machine.c:609 #6 0x00007f9231775737 in switch_core_session_thread (thread=0x7f8ed51f5170, obj=0x7f91dd8146a8) at src/switch_core_session.c:1648 #7 0x00007f9231775b25 in switch_core_session_thread_pool_worker (thread=0x7f8ed51f5170, obj=0x7f8ed51f5000) at src/switch_core_session.c:1711 #8 0x00007f9231a9bea5 in dummy_worker (opaque=0x7f8ed51f5170) at threadproc/unix/thread.c:151 #9 0x00007f9230c85064 in start_thread (arg=0x7f900b6db700) at pthread_create.c:309 #10 0x00007f923035d62d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111 Program terminated with signal SIGSEGV, Segmentation fault. #0 __GI___pthread_mutex_lock (mutex=0x0) at ../nptl/pthread_mutex_lock.c:66 66 ../nptl/pthread_mutex_lock.c: No such file or directory. (gdb) bt #0 __GI___pthread_mutex_lock (mutex=0x0) at ../nptl/pthread_mutex_lock.c:66 #1 0x00007fd953bf9db8 in apr_thread_mutex_lock (mutex=0x19101fd8) at locks/unix/thread_mutex.c:92 #2 0x00007fd95389e663 in switch_mutex_lock (lock=0x19101fd8) at src/switch_apr.c:293 #3 0x00007fd9538a7f0c in switch_channel_test_flag (channel=0x7fd619101910, flag=CF_THREAD_SLEEPING) at src/switch_channel.c:1568 #4 0x00007fd9538ed448 in switch_core_session_read_lock (session=0x7fd627d19eb8) at src/switch_core_rwlock.c:91 #5 0x00007fd9538d78fc in switch_core_session_hupall_matching_var_ans (var_name=0x7fd8b70229b1 "cc_member_pre_answer_uuid", var_val=0x7fd891c86680 "e38c1d71-e760-44bf-af63-4b54dcbd01fc", cause=SWITCH_CAUSE_ORIGINATOR_CANCEL, type=(SHT_UNANSWERED | SHT_ANSWERED)) at src/switch_core_session.c:231 #6 0x00007fd8b701eacd in callcenter_function (session=0x7fd90508dbc8, data=0x7fd6149e5a68 "Adjustments@customerdomain.com") at mod_callcenter.c:3018 #7 0x00007fd9538df841 in switch_core_session_exec (session=0x7fd90508dbc8, application_interface=0x2836ff0, arg=0x7fd6149e5a68 "Adjustments@customerdomain.com") at src/switch_core_session.c:2802 #8 0x00007fd9538defe8 in switch_core_session_execute_application_get_flags (session=0x7fd90508dbc8, app=0x7fd6149e5a58 "callcenter", arg=0x7fd6149e5a68 "Adjustments@customerdomain.com", flags=0x0) at src/switch_core_session.c:2672 #9 0x00007fd9538e168b in switch_core_standard_on_execute (session=0x7fd90508dbc8) at src/switch_core_state_machine.c:353 #10 0x00007fd9538e31c6 in switch_core_session_run (session=0x7fd90508dbc8) at src/switch_core_state_machine.c:650 #11 0x00007fd9538db737 in switch_core_session_thread (thread=0x7fd9048e9370, obj=0x7fd90508dbc8) at src/switch_core_session.c:1648 #12 0x00007fd9538dbb25 in switch_core_session_thread_pool_worker (thread=0x7fd9048e9370, obj=0x7fd9048e9200) at src/switch_core_session.c:1711 #13 0x00007fd953c01ea5 in dummy_worker (opaque=0x7fd9048e9370) at threadproc/unix/thread.c:151 #14 0x00007fd952deb064 in start_thread (arg=0x7fd891c87700) at pthread_create.c:309 #15 0x00007fd9524c362d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111 Thanks, Shaun [http://www.itec-support.co.uk/wp-content/uploads/2016/07/email_logo.jpg] Shaun Stokes - Infrastructure Analyst T : 01453 700713 E : shaun.stokes@itec-support.co.uk W : www.itec-support.co.uk Registered Address :- ITEC Support, Suite 2 Prospect House, Bath Road, Stroud, Gloucestershire GL5 3QF Company No. 06908001 CONFIDENTIALITY NOTICE This communication and the information it contains are intended for the person or organisation to which it is addressed. Its contents are confidential and may be protected in law. Unauthorised use, copying or disclosure of any of it may be unlawful. If you are not the intended recipient, please contact us immediately. The contents of any attachments in this e-mail may contain software viruses, which could damage your own computer system. While ITEC Support has taken every reasonable precaution to minimise this risk, we cannot accept liability for any damage which you sustain as a result of software viruses. You should carry out your own virus checking procedure before opening any attachment.
I’d try out the latest 1.8 release we made at ClueCon. The seg looks familiar but I would have to dig for specifics to be sure.
On Jul 26, 2018, at 4:44 AM, Shaun Stokes <shaun.stokes@itec-support.co.uk> wrote:
Hi All,
We've experienced a number of segmentation faults over time with various errors in the core dump and no clear indication of the exact cause of the problem, we suspect this is load related.
FreeSWITCH is typically handling more than 100 concurrent sessions but rarely more than 10 session per second, these segmentation faults have even occurred in the early hours of the morning with less than 10 session at the time which leads us to believe the bottleneck is on the number of simultaneous registrations\subscriptions which is the only variable which is roughly the same at all hours only decreasing and increasing slightly in the evenings and mornings.
We've created more SIP profiles to distribute registrations to no avail, we're only able to prevent segmentation faultsentirely by distributing registrations across more servers. The problems seem to occur when we have combination of: - 10,000 subscriptions and 400 registrations; reducing subscriptions to 5000 resolves the issue. - 1200 subscriptions and 950 registrations; reducing registrations to 700 resolves the issue.
We have a few FreeSWITCH servers which are dedicated for call routing only, these are processing in excess of 400 concurrent sessions and never have any issues.
Our server specs: Debian 8.9 PostgreSQL 9.4 - Optimised for best performance FreeSWITCH 1.6.20 - Using PostgreSQL backend 32GB RAM Dual socket Intel Xeon X5670 2.93Ghz - With Hyper threading enabled
Our plan is to implement SBCs in the future to handle subscriptions and registrations but in the short term we need to understand the limitations of FreeSWITCH, where are the bottlenecks, and are there any other solutions.
Looking at SIP traces at the time of these segmentation faults there are more than 30 SIP messages per second mostly subscribe, 202, notify, registration and 200. If we look at all packets on the FreeSWITCH SIP port including Application Data, ACK etc there are hundreds of packets per second.
We initially suspected the bottleneck could be the FreeSWITCH backend database but moving to PostgreSQL has had no effect.
Has anyone come across similar issues before and\or is able to offer any advice?
We're currently looking at two possibilities, a software bottleneck with-in FreeSWITCH, or a bottleneck on the hardware possibly as a result of using dual socket CPUs. We are in the process of enabling node interleaving on the hardware so we use SUMA (Sufficiently Uniform Memory Access) instead of NUMA (Non-Uniform Memory Access) which should theoretically reduce traffic on the QPI bridge slightly given that FreeSWITCH isn't optimised for NUMA.
Here's an example of some of some of the segmentation faults we've experienced:
Program terminated with signal SIGSEGV, Segmentation fault. #0 strlen () at ../sysdeps/x86_64/strlen.S:106 106 ../sysdeps/x86_64/strlen.S: No such file or directory. (gdb) bt #0 strlen () at ../sysdeps/x86_64/strlen.S:106 #1 0x00007f92302f697e in __GI___strdup (s=0xecd42c18 <error: Cannot access memory at address 0xecd42c18>) at strdup.c:41 #2 0x00007f9231849ea4 in switch_ivr_generate_xml_cdr (session=0x7f91dd8146a8, xml_cdr=0x7f900b6da748) at src/switch_ivr.c:2803 #3 0x00007f91696fc7b0 in my_on_reporting (session=0x7f91dd8146a8) at mod_xml_cdr.c:228 #4 0x00007f92317801d5 in switch_core_session_reporting_state (session=0x7f91dd8146a8) at src/switch_core_state_machine.c:938 #5 0x00007f923177bf9c in switch_core_session_run (session=0x7f91dd8146a8) at src/switch_core_state_machine.c:609 #6 0x00007f9231775737 in switch_core_session_thread (thread=0x7f8ed51f5170, obj=0x7f91dd8146a8) at src/switch_core_session.c:1648 #7 0x00007f9231775b25 in switch_core_session_thread_pool_worker (thread=0x7f8ed51f5170, obj=0x7f8ed51f5000) at src/switch_core_session.c:1711 #8 0x00007f9231a9bea5 in dummy_worker (opaque=0x7f8ed51f5170) at threadproc/unix/thread.c:151 #9 0x00007f9230c85064 in start_thread (arg=0x7f900b6db700) at pthread_create.c:309 #10 0x00007f923035d62d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111
Program terminated with signal SIGSEGV, Segmentation fault. #0 __GI___pthread_mutex_lock (mutex=0x0) at ../nptl/pthread_mutex_lock.c:66 66 ../nptl/pthread_mutex_lock.c: No such file or directory. (gdb) bt #0 __GI___pthread_mutex_lock (mutex=0x0) at ../nptl/pthread_mutex_lock.c:66 #1 0x00007fd953bf9db8 in apr_thread_mutex_lock (mutex=0x19101fd8) at locks/unix/thread_mutex.c:92 #2 0x00007fd95389e663 in switch_mutex_lock (lock=0x19101fd8) at src/switch_apr.c:293 #3 0x00007fd9538a7f0c in switch_channel_test_flag (channel=0x7fd619101910, flag=CF_THREAD_SLEEPING) at src/switch_channel.c:1568 #4 0x00007fd9538ed448 in switch_core_session_read_lock (session=0x7fd627d19eb8) at src/switch_core_rwlock.c:91 #5 0x00007fd9538d78fc in switch_core_session_hupall_matching_var_ans (var_name=0x7fd8b70229b1 "cc_member_pre_answer_uuid", var_val=0x7fd891c86680 "e38c1d71-e760-44bf-af63-4b54dcbd01fc", cause=SWITCH_CAUSE_ORIGINATOR_CANCEL, type=(SHT_UNANSWERED | SHT_ANSWERED)) at src/switch_core_session.c:231 #6 0x00007fd8b701eacd in callcenter_function (session=0x7fd90508dbc8, data=0x7fd6149e5a68 "Adjustments@ <mailto:Adjustments@customerdomain.com>customerdomain.com <mailto:Adjustments@customerdomain.com>") at mod_callcenter.c:3018 #7 0x00007fd9538df841 in switch_core_session_exec (session=0x7fd90508dbc8, application_interface=0x2836ff0, arg=0x7fd6149e5a68 "Adjustments@customerdomain.com <mailto:Adjustments@customerdomain.com>") at src/switch_core_session.c:2802 #8 0x00007fd9538defe8 in switch_core_session_execute_application_get_flags (session=0x7fd90508dbc8, app=0x7fd6149e5a58 "callcenter", arg=0x7fd6149e5a68 "Adjustments@ <mailto:Adjustments@customerdomain.com>customerdomain.com <mailto:Adjustments@customerdomain.com>", flags=0x0) at src/switch_core_session.c:2672 #9 0x00007fd9538e168b in switch_core_standard_on_execute (session=0x7fd90508dbc8) at src/switch_core_state_machine.c:353 #10 0x00007fd9538e31c6 in switch_core_session_run (session=0x7fd90508dbc8) at src/switch_core_state_machine.c:650 #11 0x00007fd9538db737 in switch_core_session_thread (thread=0x7fd9048e9370, obj=0x7fd90508dbc8) at src/switch_core_session.c:1648 #12 0x00007fd9538dbb25 in switch_core_session_thread_pool_worker (thread=0x7fd9048e9370, obj=0x7fd9048e9200) at src/switch_core_session.c:1711 #13 0x00007fd953c01ea5 in dummy_worker (opaque=0x7fd9048e9370) at threadproc/unix/thread.c:151 #14 0x00007fd952deb064 in start_thread (arg=0x7fd891c87700) at pthread_create.c:309 #15 0x00007fd9524c362d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111
Hi, Its hard to tell just looking at those examples. The best way to deal with segmentation faults is to report them with all the details necessary to debug them and to try the latest version like Mike suggests, to rule out things already fixed. If you open JIRA for them with the trace like you showed above and all the version details so we can match the code to the trace, then sometimes we can diagnose from that alone. If the open source help is not enough for your needs and you want to get a full analysis of your usage footprint and assistance with large scale commercial deployments, you can also consider working with the pro services team at https://freeswitch.com since sometimes it takes additional effort to track down things triggered by massive load. On Thu, Jul 26, 2018 at 3:44 AM, Shaun Stokes < shaun.stokes@itec-support.co.uk> wrote:
Hi All,
We've experienced a number of segmentation faults over time with various errors in the core dump and no clear indication of the exact cause of the problem, we suspect this is load related.
FreeSWITCH is typically handling more than 100 concurrent sessions but rarely more than 10 session per second, these segmentation faults have even occurred in the early hours of the morning with less than 10 session at the time which leads us to believe the bottleneck is on the number of simultaneous registrations\subscriptions which is the only variable which is roughly the same at all hours only decreasing and increasing slightly in the evenings and mornings.
We've created more SIP profiles to distribute registrations to no avail, we're only able to prevent segmentation faults entirely by distributing registrations across more servers. The problems seem to occur when we have combination of:
- 10,000 subscriptions and 400 registrations; reducing subscriptions to 5000 resolves the issue.
- 1200 subscriptions and 950 registrations; reducing registrations to 700 resolves the issue.
We have a few FreeSWITCH servers which are dedicated for call routing only, these are processing in excess of 400 concurrent sessions and never have any issues.
Our server specs:
Debian 8.9
PostgreSQL 9.4 - Optimised for best performance
FreeSWITCH 1.6.20 - Using PostgreSQL backend
32GB RAM
Dual socket Intel Xeon X5670 2.93Ghz - With Hyper threading enabled
Our plan is to implement SBCs in the future to handle subscriptions and registrations but in the short term we need to understand the limitations of FreeSWITCH, where are the bottlenecks, and are there any other solutions.
Looking at SIP traces at the time of these segmentation faults there are more than 30 SIP messages per second mostly subscribe, 202, notify, registration and 200. If we look at all packets on the FreeSWITCH SIP port including Application Data, ACK etc there are hundreds of packets per second.
We initially suspected the bottleneck could be the FreeSWITCH backend database but moving to PostgreSQL has had no effect.
Has anyone come across similar issues before and\or is able to offer any advice?
We're currently looking at two possibilities, a software bottleneck with-in FreeSWITCH, or a bottleneck on the hardware possibly as a result of using dual socket CPUs. We are in the process of enabling node interleaving on the hardware so we use SUMA (Sufficiently Uniform Memory Access) instead of NUMA (Non-Uniform Memory Access) which should theoretically reduce traffic on the QPI bridge slightly given that FreeSWITCH isn't optimised for NUMA.
Here's an example of some of some of the segmentation faults we've experienced:
Program terminated with signal SIGSEGV, Segmentation fault. #0 strlen () at ../sysdeps/x86_64/strlen.S:106 106 ../sysdeps/x86_64/strlen.S: No such file or directory.
(gdb) bt #0 strlen () at ../sysdeps/x86_64/strlen.S:106 #1 0x00007f92302f697e in __GI___strdup (s=0xecd42c18 <error: Cannot access memory at address 0xecd42c18>) at strdup.c:41 #2 0x00007f9231849ea4 in switch_ivr_generate_xml_cdr (session=0x7f91dd8146a8, xml_cdr=0x7f900b6da748) at src/switch_ivr.c:2803 #3 0x00007f91696fc7b0 in my_on_reporting (session=0x7f91dd8146a8) at mod_xml_cdr.c:228 #4 0x00007f92317801d5 in switch_core_session_reporting_state (session=0x7f91dd8146a8) at src/switch_core_state_machine.c:938 #5 0x00007f923177bf9c in switch_core_session_run (session=0x7f91dd8146a8) at src/switch_core_state_machine.c:609 #6 0x00007f9231775737 in switch_core_session_thread (thread=0x7f8ed51f5170, obj=0x7f91dd8146a8) at src/switch_core_session.c:1648 #7 0x00007f9231775b25 in switch_core_session_thread_pool_worker (thread=0x7f8ed51f5170, obj=0x7f8ed51f5000) at src/switch_core_session.c:1711 #8 0x00007f9231a9bea5 in dummy_worker (opaque=0x7f8ed51f5170) at threadproc/unix/thread.c:151 #9 0x00007f9230c85064 in start_thread (arg=0x7f900b6db700) at pthread_create.c:309 #10 0x00007f923035d62d in clone () at ../sysdeps/unix/sysv/linux/ x86_64/clone.S:111
Program terminated with signal SIGSEGV, Segmentation fault. #0 __GI___pthread_mutex_lock (mutex=0x0) at ../nptl/pthread_mutex_lock.c:66
66 ../nptl/pthread_mutex_lock.c: No such file or directory. (gdb) bt #0 __GI___pthread_mutex_lock (mutex=0x0) at ../nptl/pthread_mutex_lock.c:66
#1 0x00007fd953bf9db8 in apr_thread_mutex_lock (mutex=0x19101fd8) at locks/unix/thread_mutex.c:92 #2 0x00007fd95389e663 in switch_mutex_lock (lock=0x19101fd8) at src/switch_apr.c:293 #3 0x00007fd9538a7f0c in switch_channel_test_flag (channel=0x7fd619101910, flag=CF_THREAD_SLEEPING) at src/switch_channel.c:1568 #4 0x00007fd9538ed448 in switch_core_session_read_lock (session=0x7fd627d19eb8) at src/switch_core_rwlock.c:91 #5 0x00007fd9538d78fc in switch_core_session_hupall_matching_var_ans (var_name=0x7fd8b70229b1 "cc_member_pre_answer_uuid", var_val=0x7fd891c86680 "e38c1d71-e760-44bf-af63-4b54dcbd01fc", cause=SWITCH_CAUSE_ORIGINATOR_CANCEL, type=(SHT_UNANSWERED | SHT_ANSWERED)) at src/switch_core_session.c:231 #6 0x00007fd8b701eacd in callcenter_function (session=0x7fd90508dbc8, data=0x7fd6149e5a68 "Adjustments@customerdomain.com") at mod_callcenter.c:3018 #7 0x00007fd9538df841 in switch_core_session_exec (session=0x7fd90508dbc8, application_interface=0x2836ff0, arg=0x7fd6149e5a68 "Adjustments@customerdomain.com") at src/switch_core_session.c:2802 #8 0x00007fd9538defe8 in switch_core_session_execute_application_get_flags (session=0x7fd90508dbc8, app=0x7fd6149e5a58 "callcenter", arg=0x7fd6149e5a68 "Adjustments@customerdomain.com", flags=0x0) at src/switch_core_session.c:2672 #9 0x00007fd9538e168b in switch_core_standard_on_execute (session=0x7fd90508dbc8) at src/switch_core_state_machine.c:353 #10 0x00007fd9538e31c6 in switch_core_session_run (session=0x7fd90508dbc8) at src/switch_core_state_machine.c:650 #11 0x00007fd9538db737 in switch_core_session_thread (thread=0x7fd9048e9370, obj=0x7fd90508dbc8) at src/switch_core_session.c:1648 #12 0x00007fd9538dbb25 in switch_core_session_thread_pool_worker (thread=0x7fd9048e9370, obj=0x7fd9048e9200) at src/switch_core_session.c:1711 #13 0x00007fd953c01ea5 in dummy_worker (opaque=0x7fd9048e9370) at threadproc/unix/thread.c:151 #14 0x00007fd952deb064 in start_thread (arg=0x7fd891c87700) at pthread_create.c:309 #15 0x00007fd9524c362d in clone () at ../sysdeps/unix/sysv/linux/ x86_64/clone.S:111
Thanks,
Shaun Shaun Stokes - Infrastructure Analyst T : 01453 700713 E : shaun.stokes@itec-support.co.uk W : www.itec-support.co.uk Registered Address :- ITEC Support, Suite 2 Prospect House, Bath Road, Stroud, Gloucestershire GL5 3QF Company No. 06908001
CONFIDENTIALITY NOTICE This communication and the information it contains are intended for the person or organisation to which it is addressed. Its contents are confidential and may be protected in law. Unauthorised use, copying or disclosure of any of it may be unlawful. If you are not the intended recipient, please contact us immediately. The contents of any attachments in this e-mail may contain software viruses, which could damage your own computer system. While ITEC Support has taken every reasonable precaution to minimise this risk, we cannot accept liability for any damage which you sustain as a result of software viruses. You should carry out your own virus checking procedure before opening any attachment.
_________________________________________________________________________ Professional FreeSWITCH Services sales@freeswitch.com https://freeswitch.com
Official FreeSWITCH Sites https://freeswitch.com/oss https://freeswitch.org/confluence https://cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users https://freeswitch.com
-- Anthony Minessale II Founder, FreeSWITCH. http://freeswitch.com https://youtu.be/l_hOxzCt6X4 https://www.youtube.com/watch?v=oAxXgyx5jUw https://www.youtube.com/watch?v=9XXgW34t40s https://www.youtube.com/watch?v=NLaDpGQuZDA
Hi Anthony, Mike, Thanks for your advise. We will look at implementing FreeSWITCH 1.8 and will raise a JIRA in the meantime. Thanks, Shaun ________________________________ From: FreeSWITCH-users <freeswitch-users-bounces@lists.freeswitch.org> on behalf of Anthony Minessale <anthony.minessale@gmail.com> Sent: 30 July 2018 20:23 To: FreeSWITCH Users Help Subject: Re: [Freeswitch-users] FreeSWITCH 1.6 - Where are the bottlenecks? Hi, Its hard to tell just looking at those examples. The best way to deal with segmentation faults is to report them with all the details necessary to debug them and to try the latest version like Mike suggests, to rule out things already fixed. If you open JIRA for them with the trace like you showed above and all the version details so we can match the code to the trace, then sometimes we can diagnose from that alone. If the open source help is not enough for your needs and you want to get a full analysis of your usage footprint and assistance with large scale commercial deployments, you can also consider working with the pro services team at https://freeswitch.com since sometimes it takes additional effort to track down things triggered by massive load. On Thu, Jul 26, 2018 at 3:44 AM, Shaun Stokes <shaun.stokes@itec-support.co.uk<mailto:shaun.stokes@itec-support.co.uk>> wrote: Hi All, We've experienced a number of segmentation faults over time with various errors in the core dump and no clear indication of the exact cause of the problem, we suspect this is load related. FreeSWITCH is typically handling more than 100 concurrent sessions but rarely more than 10 session per second, these segmentation faults have even occurred in the early hours of the morning with less than 10 session at the time which leads us to believe the bottleneck is on the number of simultaneous registrations\subscriptions which is the only variable which is roughly the same at all hours only decreasing and increasing slightly in the evenings and mornings. We've created more SIP profiles to distribute registrations to no avail, we're only able to prevent segmentation faults entirely by distributing registrations across more servers. The problems seem to occur when we have combination of: - 10,000 subscriptions and 400 registrations; reducing subscriptions to 5000 resolves the issue. - 1200 subscriptions and 950 registrations; reducing registrations to 700 resolves the issue. We have a few FreeSWITCH servers which are dedicated for call routing only, these are processing in excess of 400 concurrent sessions and never have any issues. Our server specs: Debian 8.9 PostgreSQL 9.4 - Optimised for best performance FreeSWITCH 1.6.20 - Using PostgreSQL backend 32GB RAM Dual socket Intel Xeon X5670 2.93Ghz - With Hyper threading enabled Our plan is to implement SBCs in the future to handle subscriptions and registrations but in the short term we need to understand the limitations of FreeSWITCH, where are the bottlenecks, and are there any other solutions. Looking at SIP traces at the time of these segmentation faults there are more than 30 SIP messages per second mostly subscribe, 202, notify, registration and 200. If we look at all packets on the FreeSWITCH SIP port including Application Data, ACK etc there are hundreds of packets per second. We initially suspected the bottleneck could be the FreeSWITCH backend database but moving to PostgreSQL has had no effect. Has anyone come across similar issues before and\or is able to offer any advice? We're currently looking at two possibilities, a software bottleneck with-in FreeSWITCH, or a bottleneck on the hardware possibly as a result of using dual socket CPUs. We are in the process of enabling node interleaving on the hardware so we use SUMA (Sufficiently Uniform Memory Access) instead of NUMA (Non-Uniform Memory Access) which should theoretically reduce traffic on the QPI bridge slightly given that FreeSWITCH isn't optimised for NUMA. Here's an example of some of some of the segmentation faults we've experienced: Program terminated with signal SIGSEGV, Segmentation fault. #0 strlen () at ../sysdeps/x86_64/strlen.S:106 106 ../sysdeps/x86_64/strlen.S: No such file or directory. (gdb) bt #0 strlen () at ../sysdeps/x86_64/strlen.S:106 #1 0x00007f92302f697e in __GI___strdup (s=0xecd42c18 <error: Cannot access memory at address 0xecd42c18>) at strdup.c:41 #2 0x00007f9231849ea4 in switch_ivr_generate_xml_cdr (session=0x7f91dd8146a8, xml_cdr=0x7f900b6da748) at src/switch_ivr.c:2803 #3 0x00007f91696fc7b0 in my_on_reporting (session=0x7f91dd8146a8) at mod_xml_cdr.c:228 #4 0x00007f92317801d5 in switch_core_session_reporting_state (session=0x7f91dd8146a8) at src/switch_core_state_machine.c:938 #5 0x00007f923177bf9c in switch_core_session_run (session=0x7f91dd8146a8) at src/switch_core_state_machine.c:609 #6 0x00007f9231775737 in switch_core_session_thread (thread=0x7f8ed51f5170, obj=0x7f91dd8146a8) at src/switch_core_session.c:1648 #7 0x00007f9231775b25 in switch_core_session_thread_pool_worker (thread=0x7f8ed51f5170, obj=0x7f8ed51f5000) at src/switch_core_session.c:1711 #8 0x00007f9231a9bea5 in dummy_worker (opaque=0x7f8ed51f5170) at threadproc/unix/thread.c:151 #9 0x00007f9230c85064 in start_thread (arg=0x7f900b6db700) at pthread_create.c:309 #10 0x00007f923035d62d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111 Program terminated with signal SIGSEGV, Segmentation fault. #0 __GI___pthread_mutex_lock (mutex=0x0) at ../nptl/pthread_mutex_lock.c:66 66 ../nptl/pthread_mutex_lock.c: No such file or directory. (gdb) bt #0 __GI___pthread_mutex_lock (mutex=0x0) at ../nptl/pthread_mutex_lock.c:66 #1 0x00007fd953bf9db8 in apr_thread_mutex_lock (mutex=0x19101fd8) at locks/unix/thread_mutex.c:92 #2 0x00007fd95389e663 in switch_mutex_lock (lock=0x19101fd8) at src/switch_apr.c:293 #3 0x00007fd9538a7f0c in switch_channel_test_flag (channel=0x7fd619101910, flag=CF_THREAD_SLEEPING) at src/switch_channel.c:1568 #4 0x00007fd9538ed448 in switch_core_session_read_lock (session=0x7fd627d19eb8) at src/switch_core_rwlock.c:91 #5 0x00007fd9538d78fc in switch_core_session_hupall_matching_var_ans (var_name=0x7fd8b70229b1 "cc_member_pre_answer_uuid", var_val=0x7fd891c86680 "e38c1d71-e760-44bf-af63-4b54dcbd01fc", cause=SWITCH_CAUSE_ORIGINATOR_CANCEL, type=(SHT_UNANSWERED | SHT_ANSWERED)) at src/switch_core_session.c:231 #6 0x00007fd8b701eacd in callcenter_function (session=0x7fd90508dbc8, data=0x7fd6149e5a68 "Adjustments@customerdomain.com<http://customerdomain.com>") at mod_callcenter.c:3018 #7 0x00007fd9538df841 in switch_core_session_exec (session=0x7fd90508dbc8, application_interface=0x2836ff0, arg=0x7fd6149e5a68 "Adjustments@customerdomain.com<mailto:Adjustments@customerdomain.com>") at src/switch_core_session.c:2802 #8 0x00007fd9538defe8 in switch_core_session_execute_application_get_flags (session=0x7fd90508dbc8, app=0x7fd6149e5a58 "callcenter", arg=0x7fd6149e5a68 "Adjustments@customerdomain.com<http://customerdomain.com>", flags=0x0) at src/switch_core_session.c:2672 #9 0x00007fd9538e168b in switch_core_standard_on_execute (session=0x7fd90508dbc8) at src/switch_core_state_machine.c:353 #10 0x00007fd9538e31c6 in switch_core_session_run (session=0x7fd90508dbc8) at src/switch_core_state_machine.c:650 #11 0x00007fd9538db737 in switch_core_session_thread (thread=0x7fd9048e9370, obj=0x7fd90508dbc8) at src/switch_core_session.c:1648 #12 0x00007fd9538dbb25 in switch_core_session_thread_pool_worker (thread=0x7fd9048e9370, obj=0x7fd9048e9200) at src/switch_core_session.c:1711 #13 0x00007fd953c01ea5 in dummy_worker (opaque=0x7fd9048e9370) at threadproc/unix/thread.c:151 #14 0x00007fd952deb064 in start_thread (arg=0x7fd891c87700) at pthread_create.c:309 #15 0x00007fd9524c362d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111 Thanks, Shaun Shaun Stokes - Infrastructure Analyst T : 01453 700713 E : shaun.stokes@itec-support.co.uk W : www.itec-support.co.uk Registered Address :- ITEC Support, Suite 2 Prospect House, Bath Road, Stroud, Gloucestershire GL5 3QF Company No. 06908001 CONFIDENTIALITY NOTICE This communication and the information it contains are intended for the person or organisation to which it is addressed. Its contents are confidential and may be protected in law. Unauthorised use, copying or disclosure of any of it may be unlawful. If you are not the intended recipient, please contact us immediately. The contents of any attachments in this e-mail may contain software viruses, which could damage your own computer system. While ITEC Support has taken every reasonable precaution to minimise this risk, we cannot accept liability for any damage which you sustain as a result of software viruses. You should carry out your own virus checking procedure before opening any attachment. _________________________________________________________________________ Professional FreeSWITCH Services sales@freeswitch.com<mailto:sales@freeswitch.com> https://freeswitch.com Official FreeSWITCH Sites https://freeswitch.com/oss https://freeswitch.org/confluence https://cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org<mailto:FreeSWITCH-users@lists.freeswitch.org> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users https://freeswitch.com -- Anthony Minessale II Founder, FreeSWITCH. http://freeswitch.com https://youtu.be/l_hOxzCt6X4 https://www.youtube.com/watch?v=oAxXgyx5jUw https://www.youtube.com/watch?v=9XXgW34t40s https://www.youtube.com/watch?v=NLaDpGQuZDA
Hi All, We have an issue with calls intermittently not ending when receiving faxes, when this happens the call finishes with 'FLOW T.30 Call completed' but never hangs up, the calls can run for days before they're identified. It's as if the call gets stuck in mod_spandsp_fax instead of being passed back to the dialplan. Has anyone come across this problem before and\or able to advise? Example below. Thanks, Shaun The callers number has been replaced with nnnnnnnnnnnn The destination number has been replaced with dddddddddddd IP address of the sending FreeSWITCH has been replaced with y.y.y.y IP address of the receiving FreeSWITCH has been replaced with x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [NOTICE] switch_channel.c:1104 New Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y [122dd615-7eec-47d4-a7d6-2bca18ca5ed6] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_NEW (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:9873 sofia/external/+nnnnnnnnnnnn@y.y.y.y receiving invite from y.y.y.y:5095 version: 1.6.20 git 43a9feb 2018-05-07 18:56:11Z 64bit 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [received][100] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:7094 Remote SDP: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 v=0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 o=FreeSWITCH 1532659742 1532659743 IN IP4 y.y.y.y 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 s=FreeSWITCH 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 c=IN IP4 y.y.y.y 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 t=0 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 m=audio 24082 RTP/AVP 9 8 101 13 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:9 G722/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:8 PCMA/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:101 telephone-event/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=fmtp:101 0-16 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=ptime:20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/+nnnnnnnnnnnn@y.y.y.y G722/8000 20 ms 160 samples 64000 bits 1 channels 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_codec.c:111 sofia/external/+nnnnnnnnnnnn@y.y.y.y Original read codec set to G722:9 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4767 sofia/external/+nnnnnnnnnnnn@y.y.y.y Set 2833 dtmf send payload to 101 recv payload to 101 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:7507 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State Change CS_NEW -> CS_INIT 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:603 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State NEW 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_INIT (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State INIT 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_sofia.c:90 sofia/external/+nnnnnnnnnnnn@y.y.y.y SOFIA INIT 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:40 sofia/external/+nnnnnnnnnnnn@y.y.y.y Standard INIT 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:48 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State Change CS_INIT -> CS_ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State INIT going to sleep 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_ROUTING (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_channel.c:2249 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Callstate Change DOWN -> RINGING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_sofia.c:143 sofia/external/+nnnnnnnnnnnn@y.y.y.y SOFIA ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:236 sofia/external/+nnnnnnnnnnnn@y.y.y.y Standard ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [INFO] mod_dialplan_xml.c:637 Processing +nnnnnnnnnnnn <+nnnnnnnnnnnn>->+dddddddddddd in context external_profile 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_dialplan_xml.c:650 Getting dialplan from alternate path: /directory_path/freeswitch/config_path/external_profile.xml 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [external_profile->external_profile] continue=false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [external_profile] destination_number(+dddddddddddd) =~ /^(.*?)$/ break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(call_direction=inbound) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action export(t38_passthru=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action export(ignore_display_updates=true) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y export(ignore_display_updates=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [ignore_display_updates]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(ringback=%(400,200,400,450);%(400,2000,400,450)) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action ring_ready() 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(send_silence_when_idle=400) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(send_silence_when_idle=400) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [send_silence_when_idle]=[400] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action export(sip_cid_type=none) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y export(sip_cid_type=none) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_cid_type]=[none] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action lua(external_profile.lua) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State Change CS_ROUTING -> CS_EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State ROUTING going to sleep 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_EXECUTE (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_sofia.c:198 sofia/external/+nnnnnnnnnnnn@y.y.y.y SOFIA EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:328 sofia/external/+nnnnnnnnnnnn@y.y.y.y Standard EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(call_direction=inbound) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [call_direction]=[inbound] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y export(t38_passthru=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [t38_passthru]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(ringback=%(400,200,400,450);%(400,2000,400,450)) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [ringback]=[%(400,200,400,450);%(400,2000,400,450)] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y ring_ready() 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [NOTICE] mod_sofia.c:2286 Ring-Ready sofia/external/+nnnnnnnnnnnn@y.y.y.y! 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [early][180] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [NOTICE] mod_dptools.c:1028 Ring Ready sofia/external/+nnnnnnnnnnnn@y.y.y.y! 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y lua(external_profile.lua) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(hangup_after_bridge, true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(caller_id_number, /+nnnnnnnnnnnn) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y bridge_export(effective_caller_id_number=/+nnnnnnnnnnnn) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_channel.c:1296 EXPORT (bridge_export_vars) [effective_caller_id_number]=[/+nnnnnnnnnnnn] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y bridge_export(effective_caller_id_name=/+nnnnnnnnnnnn) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_channel.c:1296 EXPORT (bridge_export_vars) [effective_caller_id_name]=[/+nnnnnnnnnnnn] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(domain_uuid, 8f80b77f-52f8-4996-8aca-7d17c958cd02) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(domain, customersdomain.com) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(domain_name, customersdomain.com) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(context, customersdomain.com) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_ivr.c:2165 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State Change CS_EXECUTE -> CS_ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/+nnnnnnnnnnnn@y.y.y.y to XML[8000@customersdomain.com] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:907 transfer result: 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:1112 sofia/external/+nnnnnnnnnnnn@y.y.y.y destroy/unlink session from object 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State EXECUTE going to sleep 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_ROUTING (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] mod_sofia.c:143 sofia/external/+nnnnnnnnnnnn@y.y.y.y SOFIA ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_core_state_machine.c:236 sofia/external/+nnnnnnnnnnnn@y.y.y.y Standard ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [INFO] mod_dialplan_xml.c:637 Processing +nnnnnnnnnnnn <+nnnnnnnnnnnn>->8000 in context customersdomain.com 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [customersdomain.com->user_exists] continue=true 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [user_exists] () =~ // break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(user_exists=false) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.052661 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [user_exists]=[false] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [variables] () =~ // break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action export(origination_callee_id_name=${destination_number}) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(sip_copy_multipart=false) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [customersdomain.com->record_variables] continue=true 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [record_variables] () =~ // break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action lua(app.lua call_recording set) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [customersdomain.com->user_record] continue=true 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [user_record] () =~ // break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(user_record=) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [user_record]=[UNDEF] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(from_user_exists=false) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [from_user_exists]=[false] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [redial] () =~ // break=never 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [customersdomain.com->speed_dial] continue=false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(emergency_caller_id_name=) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [emergency_caller_id_name]=[UNDEF] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(emergency_caller_id_number=) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [emergency_caller_id_number]=[UNDEF] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(outbound_caller_id_name=) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [outbound_caller_id_name]=[UNDEF] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(outbound_caller_id_number=) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [outbound_caller_id_number]=[UNDEF] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [customersdomain.com->local_distinctive_ring] continue=true 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [Fax] destination_number(8000) =~ /^8000$/ break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(api_hangup_hook=lua app/fax/resources/scripts/hangup_rx.lua) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action answer() 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action playback(silence_stream://2000) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(fax_uuid=4d9b08f1-eac6-4c6d-bccd-ee78dd626e06) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(fax_enable_t38=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(fax_enable_t38_request=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(fax_use_ecm=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(fax_verbose=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(last_fax=${sip_from_user_stripped}-${strftime(%Y-%m-%d-%H-%M-%S)}) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action rxfax(/media/store/storage/fax/customersdomain.com/8000/inbox/${last_fax}.tif) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action hangup() 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State Change CS_ROUTING -> CS_EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State ROUTING going to sleep 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_EXECUTE (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_sofia.c:198 sofia/external/+nnnnnnnnnnnn@y.y.y.y SOFIA EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_core_state_machine.c:328 sofia/external/+nnnnnnnnnnnn@y.y.y.y Standard EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y export(origination_callee_id_name=8000) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[8000] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(RFC2822_DATE=Fri, 27 Jul 2018 10:30:17 +0100) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [RFC2822_DATE]=[Fri, 27 Jul 2018 10:30:17 +0100] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(sip_copy_multipart=false) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.082683 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [sip_copy_multipart]=[false] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y lua(app.lua call_recording set) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(RECORD_APPEND, true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(RECORD_MIN_SEC, 0) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y bridge_export(rec_file=/directory_path/freeswitch/recording_path/customersdomain.com/archive/2018/Jul/27/122dd615-7eec-47d4-a7d6-2bca18ca5ed6.mp3) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_channel.c:1296 EXPORT (bridge_export_vars) [rec_file]=[/directory_path/freeswitch/recording_path/customersdomain.com/archive/2018/Jul/27/122dd615-7eec-47d4-a7d6-2bca18ca5ed6.mp3] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y bridge_export(rec_uuid=122dd615-7eec-47d4-a7d6-2bca18ca5ed6) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_channel.c:1296 EXPORT (bridge_export_vars) [rec_uuid]=[122dd615-7eec-47d4-a7d6-2bca18ca5ed6] 2018-07-27 10:30:17.182696 [NOTICE] switch_cpp.cpp:1365 uuid_getvar 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 sofia_profile_name: external 2018-07-27 10:30:17.182696 [NOTICE] switch_cpp.cpp:1365 call_recording rec_file: /directory_path/freeswitch/recording_path/customersdomain.com/archive/2018/Jul/27/122dd615-7eec-47d4-a7d6-2bca18ca5ed6.mp3 2018-07-27 10:30:17.182696 [NOTICE] switch_cpp.cpp:1365 call_recording rec_uuid: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_cpp.cpp:1112 sofia/external/+nnnnnnnnnnnn@y.y.y.y destroy/unlink session from object 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y hash(insert/customersdomain.com-last_dial//+nnnnnnnnnnnn/8000) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(api_hangup_hook=lua app/fax/resources/scripts/hangup_rx.lua) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [api_hangup_hook]=[lua app/fax/resources/scripts/hangup_rx.lua] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y answer() 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/+nnnnnnnnnnnn@y.y.y.y] x.x.x.x port 27332 -> y.y.y.y port 24082 codec: 9 ms: 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_core_media.c:7180 sofia/external/+nnnnnnnnnnnn@y.y.y.y Set 2833 dtmf send payload to 101 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_core_media.c:7187 sofia/external/+nnnnnnnnnnnn@y.y.y.y Set 2833 dtmf receive payload to 101 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_core_media.c:7210 sofia/external/+nnnnnnnnnnnn@y.y.y.y Set rtp dtmf delay to 40 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_core_media.c:7216 Set comfort noise payload to 13 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/+nnnnnnnnnnnn@y.y.y.y: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 v=0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 o=FreeSWITCH 1532656485 1532656486 IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 s=FreeSWITCH 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 c=IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 t=0 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 m=audio 27332 RTP/AVP 9 101 13 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:9 G722/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:101 telephone-event/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=fmtp:101 0-16 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:13 CN/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=ptime:20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=sendrecv 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/+nnnnnnnnnnnn@y.y.y.y] has been answered 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_channel.c:3773 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Callstate Change RINGING -> ACTIVE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [completed][200] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y playback(silence_stream://2000) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@16000hz 1 channels 20ms 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.192671 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [ready][200] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.242687 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] switch_ivr_play_say.c:1942 done playing file silence_stream://2000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(fax_uuid=4d9b08f1-eac6-4c6d-bccd-ee78dd626e06) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [fax_uuid]=[4d9b08f1-eac6-4c6d-bccd-ee78dd626e06] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(fax_enable_t38=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [fax_enable_t38]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(fax_enable_t38_request=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [fax_enable_t38_request]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(fax_use_ecm=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [fax_use_ecm]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(fax_verbose=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [fax_verbose]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(last_fax=nnnnnnnnnnnn-2018-07-27-10-30-19) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [last_fax]=[nnnnnnnnnnnn-2018-07-27-10-30-19] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y rxfax(/media/store/storage/fax/customersdomain.com/8000/inbox/nnnnnnnnnnnn-2018-07-27-10-30-19.tif) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.192630 [DEBUG] mod_spandsp_fax.c:1448 Raw read codec activation Success L16 20000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.192630 [DEBUG] switch_core_codec.c:223 sofia/external/+nnnnnnnnnnnn@y.y.y.y Push codec L16:100 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.192630 [DEBUG] mod_spandsp_fax.c:1466 Raw write codec activation Success L16 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.192630 [DEBUG] switch_ivr.c:195 Codec Activated L16@8000hz 1 channels 20ms 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.202705 [NOTICE] switch_core_io.c:1202 Activating write resampler 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.202705 [DEBUG] switch_core_io.c:1448 Engaging Write Buffer at 640 bytes to accommodate 640->640 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.242689 [NOTICE] switch_core_io.c:570 Activating read resampler 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.462696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.482690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.502696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.522686 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Abort (-8) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.582696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.602696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.642696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.002708 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.022686 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.422690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Abort (-8) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.442690 [DEBUG] switch_core_media.c:9390 sofia/external/+nnnnnnnnnnnn@y.y.y.y image media sdp: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 v=0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 o=FreeSWITCH 1532656485 1532656487 IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 s=FreeSWITCH 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 c=IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 t=0 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 m=image 27332 udptl t38 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxVersion:0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38MaxBitRate:14400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxFillBitRemoval 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxRateManagement:transferredTCF 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxBuffer:2000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxDatagram:400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxUdpEC:t38UDPRedundancy 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.442690 [DEBUG] sofia_glue.c:1295 sofia/external/+nnnnnnnnnnnn@y.y.y.y sending invite version: 1.6.20 git 43a9feb 2018-05-07 18:56:11Z 64bit 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Local SDP: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 v=0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 o=FreeSWITCH 1532656485 1532656487 IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 s=FreeSWITCH 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 c=IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 t=0 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 m=image 27332 udptl t38 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxVersion:0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38MaxBitRate:14400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxFillBitRemoval 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxRateManagement:transferredTCF 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxBuffer:2000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxDatagram:400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxUdpEC:t38UDPRedundancy 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.442690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.442690 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [calling][0] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.642707 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [completing][200] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.642707 [DEBUG] sofia.c:7094 Remote SDP: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 v=0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 o=FreeSWITCH 1532659742 1532659744 IN IP4 y.y.y.y 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 s=FreeSWITCH 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 c=IN IP4 y.y.y.y 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 t=0 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 m=image 24082 udptl t38 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxVersion:0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38MaxBitRate:14400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxFillBitRemoval 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxRateManagement:transferredTCF 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxBuffer:300 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxDatagram:176 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxUdpEC:t38UDPRedundancy 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.662688 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [ready][200] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.662688 [DEBUG] switch_core_media.c:4041 sofia/external/+nnnnnnnnnnnn@y.y.y.y T38 ACCEPT on response 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.662688 [DEBUG] switch_core_media.c:4146 sofia/external/+nnnnnnnnnnnn@y.y.y.y T38 IS POSSIBLE on response 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.662688 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/external/+nnnnnnnnnnnn@y.y.y.y. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.682689 [DEBUG] switch_core_media.c:9751 Remote address:port [y.y.y.y:24082] has not changed. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.682689 [DEBUG] switch_core_media.c:9751 Remote address:port [y.y.y.y:24082] has not changed. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase A_CED, state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Starting answer mode 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase A_CED to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T2 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state ANSWERING to R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Retry number 1 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:34.712699 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:34.712699 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:34.712699 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:34.712699 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CRP - CRC OK (clean) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (4800 remaining) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: CRP with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: ff 13 1b 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Retry number 2 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:42.392675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:42.392675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:42.392675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:42.392675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CRP - CRC OK (clean) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (4800 remaining) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: CRP with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: ff 13 1b 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Retry number 3 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:50.072694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:50.072694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:50.072694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:50.072694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CRP - CRC OK (clean) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (4800 remaining) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: CRP with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: ff 13 1b 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Retry number 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:57.752681 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:57.752681 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:57.752681 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:57.752681 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CRP - CRC OK (clean) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (4800 remaining) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: CRP with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: ff 13 1b 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Retry number 5 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:05.432688 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:05.432688 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:05.432688 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:05.432688 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CRP - CRC OK (clean) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (4800 remaining) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: CRP with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: ff 13 1b 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Too many retries. Giving up. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Status changing to Disconnected after permitted retries 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase D_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state R to C 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DCN with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 fa 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state C 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state C 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to D_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state C 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state C 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Starting final pause before disconnecting 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase D_TX to E 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 1 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state C to B 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase E, state B 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:496 ============================================================================== 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:508 Fax processing not successful - result (48) Disconnected after permitted retries. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:513 Remote station id: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:514 Local station id: SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:517 Total fax pages: 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:518 Image resolution: 0x0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate: 14400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:521 ECM status off 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:522 remote country: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:523 remote vendor: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:524 remote model: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:526 ============================================================================== 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state B to CALL_FINISHED 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase E to CALL_FINISHED 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 9 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 9 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T FAX exchange complete 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Call completed
We are building successfully and have an msi posted now. mod_av did not used to build previously, it was added and should now be there.
On Jul 24, 2018, at 7:06 AM, Bipin Patel <bipin@xbipin.com> wrote:
hi,
i was using VS2017 with SDK10 on master and it was building just fine but today FS1.8 code released to master and that broke compile on windows using VS2017 and SDK10, FreeSwitchConsole.exe doesnt build even though the rest of it compiled just fine and also mod_av used to build find on older master that totally broke but i can live without it.
-- Regards, Bipin
_________________________________________________________________________ Professional FreeSWITCH Services sales@freeswitch.com <mailto:sales@freeswitch.com> https://freeswitch.com <https://freeswitch.com/>
Official FreeSWITCH Sites https://freeswitch.com/oss <https://freeswitch.com/oss> https://freeswitch.org/confluence <https://freeswitch.org/confluence> https://cluecon.com <https://cluecon.com/>
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org <mailto:FreeSWITCH-users@lists.freeswitch.org> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users <http://lists.freeswitch.org/mailman/listinfo/freeswitch-users> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users <http://lists.freeswitch.org/mailman/options/freeswitch-users> https://freeswitch.com <https://freeswitch.com/>
participants (5)
-
Abaci B
-
Anthony Minessale
-
Bipin Patel
-
Michael Jerris
-
Shaun Stokes