DMR to Analog Bridge PTT assert quirk


David Ranch
 

Hello Everyone,

I have a working Brandmeister talk group to Analog Bridge setup working here but I see a PTT quirk that I'm curious if someone can comment on. 


If I key up the local DMR repeater that's in the Brandmeister talk group, this Raspberry Pi 3B+ setup using a URI-X sound card will light up the PTT LED on the URIX but then go off, then light back up and stay lit.  Beyond that, everything works as it should.  I see this behavior regardless if I use the SW IMBE vocoder or if I use a NW Digital THumbDV.  

Any thoughts on how to fix this "ACTIVE_FLAP" issue?

I've also attached a 7.7MB video of this behavior though I don't know if the Groups.IO system will accept it.  Let's see.

--David
KI6ZHD



Asterisk output with "rpt debug level 6"
--
*CLI> [Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:16] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
--


MMDVM STDOUT output
--
M: 2019-09-14 19:25:16.498 DMR Slot 2, received network voice header from KI6ZHD to TG WB5WXI
M: 2019-09-14 19:25:17.262 DMR Talker Alias (Data Format 1, Received 6/23 char): 'KI6ZHD'
D: 2019-09-14 19:25:17.789 DMR Slot 2, lost audio for 409ms filling in
M: 2019-09-14 19:25:17.980 DMR Talker Alias (Data Format 1, Received 13/23 char): 'KI6ZHD KI6ZHD'
M: 2019-09-14 19:25:18.461 DMR Slot 2, received network end of voice transmission, 2.3 seconds, 15% packet loss, BER: 0.0%
--


Analog_bridge STDOUT output:
--
[ Nothing shown after it starts up successfully ]
--


Steve N4IRS
 

David,
It might be interesting to see you log when the issue happens. I am talking about a snip of the ASL, AB, MB logs at the point in time when the issue happens.

On 9/14/19 3:32 PM, David Ranch wrote:
Hello Everyone,

I have a working Brandmeister talk group to Analog Bridge setup working here but I see a PTT quirk that I'm curious if someone can comment on. 


If I key up the local DMR repeater that's in the Brandmeister talk group, this Raspberry Pi 3B+ setup using a URI-X sound card will light up the PTT LED on the URIX but then go off, then light back up and stay lit.  Beyond that, everything works as it should.  I see this behavior regardless if I use the SW IMBE vocoder or if I use a NW Digital THumbDV.  

Any thoughts on how to fix this "ACTIVE_FLAP" issue?

I've also attached a 7.7MB video of this behavior though I don't know if the Groups.IO system will accept it.  Let's see.

--David
KI6ZHD



Asterisk output with "rpt debug level 6"
--
*CLI> [Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:16] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
--


MMDVM STDOUT output
--
M: 2019-09-14 19:25:16.498 DMR Slot 2, received network voice header from KI6ZHD to TG WB5WXI
M: 2019-09-14 19:25:17.262 DMR Talker Alias (Data Format 1, Received 6/23 char): 'KI6ZHD'
D: 2019-09-14 19:25:17.789 DMR Slot 2, lost audio for 409ms filling in
M: 2019-09-14 19:25:17.980 DMR Talker Alias (Data Format 1, Received 13/23 char): 'KI6ZHD KI6ZHD'
M: 2019-09-14 19:25:18.461 DMR Slot 2, received network end of voice transmission, 2.3 seconds, 15% packet loss, BER: 0.0%
--


Analog_bridge STDOUT output:
--
[ Nothing shown after it starts up successfully ]
--



David Ranch
 


Hey Steve,

I'm not 100% sure what you're looking for but here is the output from:

   tail -f /var/log/asterisk/messages &
   tail -f /var/log/Analog_Bridge.log &
   tail -f /var/log/MMDVM_Bridge-`date +%Y-%m-%d`.log &


Let me know if there is anything else you want to see:


--
[root@ww6bay-asl log]# M: 2019-09-14 21:08:36.887 DMR Slot 2, received network voice header from KI6ZHD to TG WB5WXI
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** rx key
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** rx key
[Sep 14 14:08:36] NOTICE[5333] app_rpt.c: Last = 1999, K
[Sep 14 14:08:36] NOTICE[5333] app_rpt.c: Tracepoint rpt_telemetry() entered mode=0
[Sep 14 14:08:36] NOTICE[5333] app_rpt.c: Tracepoint rpt_telemetry() exit
[Sep 14 14:08:36] NOTICE[5431] app_rpt.c: [1998] telemetry chan to teleconf
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** rx unkey
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** cos state unkey
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** rx un-key
[Sep 14 14:08:37] NOTICE[5335] app_rpt.c: **** rx key
[Sep 14 14:08:37] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 14:08:37] NOTICE[5335] app_rpt.c: **** rx key
[Sep 14 14:08:37] NOTICE[5333] app_rpt.c: Last = 1999, K
M: 2019-09-14 21:08:37.687 DMR Talker Alias (Data Format 1, Received 6/23 char): 'KI6ZHD'
M: 2019-09-14 21:08:38.378 DMR Talker Alias (Data Format 1, Received 13/23 char): 'KI6ZHD KI6ZHD'
M: 2019-09-14 21:08:38.839 DMR Slot 2, received network end of voice transmission, 1.9 seconds, 0% packet loss, BER: 0.0%
[Sep 14 14:08:39] NOTICE[5335] app_rpt.c: **** rx unkey
[Sep 14 14:08:39] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 14:08:39] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 14:08:39] NOTICE[5335] app_rpt.c: **** cos state unkey
[Sep 14 14:08:39] NOTICE[5335] app_rpt.c: **** rx un-key
--


Btw.. I'm seeing a lot of error messages like the following today.  Are you aware of Brandmeister having issues today?
--
M: 2019-09-14 20:58:41.665 DMR, Logged into the master successfully
E: 2019-09-14 20:59:41.252 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 20:59:41.252 DMR, Closing DMR Network
M: 2019-09-14 20:59:41.252 DMR, Opening DMR Network
M: 2019-09-14 20:59:51.491 DMR, Logged into the master successfully
E: 2019-09-14 21:00:41.753 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:00:41.753 DMR, Closing DMR Network
M: 2019-09-14 21:00:41.753 DMR, Opening DMR Network
W: 2019-09-14 21:00:51.570 DMR, Login to the master has failed, retrying login ...
M: 2019-09-14 21:00:51.957 DMR, Logged into the master successfully
E: 2019-09-14 21:01:51.570 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:01:51.570 DMR, Closing DMR Network
M: 2019-09-14 21:01:51.570 DMR, Opening DMR Network
M: 2019-09-14 21:02:01.815 DMR, Logged into the master successfully
E: 2019-09-14 21:02:52.044 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:02:52.044 DMR, Closing DMR Network
M: 2019-09-14 21:02:52.044 DMR, Opening DMR Network
W: 2019-09-14 21:03:01.897 DMR, Login to the master has failed, retrying login ...
M: 2019-09-14 21:03:02.238 DMR, Logged into the master successfully
E: 2019-09-14 21:04:01.902 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:04:01.902 DMR, Closing DMR Network
M: 2019-09-14 21:04:01.903 DMR, Opening DMR Network
M: 2019-09-14 21:04:12.143 DMR, Logged into the master successfully
E: 2019-09-14 21:05:02.340 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:05:02.340 DMR, Closing DMR Network
M: 2019-09-14 21:05:02.340 DMR, Opening DMR Network
W: 2019-09-14 21:05:12.223 DMR, Login to the master has failed, retrying login ...
M: 2019-09-14 21:05:12.556 DMR, Logged into the master successfully
E: 2019-09-14 21:06:12.232 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:06:12.232 DMR, Closing DMR Network
M: 2019-09-14 21:06:12.232 DMR, Opening DMR Network
M: 2019-09-14 21:06:22.467 DMR, Logged into the master successfully
E: 2019-09-14 21:07:12.670 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:07:12.670 DMR, Closing DMR Network
M: 2019-09-14 21:07:12.670 DMR, Opening DMR Network
W: 2019-09-14 21:07:22.557 DMR, Login to the master has failed, retrying login ...
M: 2019-09-14 21:07:22.874 DMR, Logged into the master successfully
E: 2019-09-14 21:08:22.566 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:08:22.567 DMR, Closing DMR Network
M: 2019-09-14 21:08:22.567 DMR, Opening DMR Network
M: 2019-09-14 21:08:32.791 DMR, Logged into the master successfully
--




On 09/14/2019 12:54 PM, Steve N4IRS wrote:
David,
It might be interesting to see you log when the issue happens. I am talking about a snip of the ASL, AB, MB logs at the point in time when the issue happens.

On 9/14/19 3:32 PM, David Ranch wrote:
Hello Everyone,

I have a working Brandmeister talk group to Analog Bridge setup working here but I see a PTT quirk that I'm curious if someone can comment on. 


If I key up the local DMR repeater that's in the Brandmeister talk group, this Raspberry Pi 3B+ setup using a URI-X sound card will light up the PTT LED on the URIX but then go off, then light back up and stay lit.  Beyond that, everything works as it should.  I see this behavior regardless if I use the SW IMBE vocoder or if I use a NW Digital THumbDV.  

Any thoughts on how to fix this "ACTIVE_FLAP" issue?

I've also attached a 7.7MB video of this behavior though I don't know if the Groups.IO system will accept it.  Let's see.

--David
KI6ZHD



Asterisk output with "rpt debug level 6"
--
*CLI> [Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:16] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
--


MMDVM STDOUT output
--
M: 2019-09-14 19:25:16.498 DMR Slot 2, received network voice header from KI6ZHD to TG WB5WXI
M: 2019-09-14 19:25:17.262 DMR Talker Alias (Data Format 1, Received 6/23 char): 'KI6ZHD'
D: 2019-09-14 19:25:17.789 DMR Slot 2, lost audio for 409ms filling in
M: 2019-09-14 19:25:17.980 DMR Talker Alias (Data Format 1, Received 13/23 char): 'KI6ZHD KI6ZHD'
M: 2019-09-14 19:25:18.461 DMR Slot 2, received network end of voice transmission, 2.3 seconds, 15% packet loss, BER: 0.0%
--


Analog_bridge STDOUT output:
--
[ Nothing shown after it starts up successfully ]
--




Steve N4IRS
 

What I want to see is a snip from each log when receiving inbound traffic from DMR. The idea would be to see the start of transmission received on MB, the same signal passed to AB and finally to ASL.

On 9/14/19 5:11 PM, David Ranch wrote:

Hey Steve,

I'm not 100% sure what you're looking for but here is the output from:

   tail -f /var/log/asterisk/messages &
   tail -f /var/log/Analog_Bridge.log &
   tail -f /var/log/MMDVM_Bridge-`date +%Y-%m-%d`.log &


Let me know if there is anything else you want to see:


--
[root@ww6bay-asl log]# M: 2019-09-14 21:08:36.887 DMR Slot 2, received network voice header from KI6ZHD to TG WB5WXI
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** rx key
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** rx key
[Sep 14 14:08:36] NOTICE[5333] app_rpt.c: Last = 1999, K
[Sep 14 14:08:36] NOTICE[5333] app_rpt.c: Tracepoint rpt_telemetry() entered mode=0
[Sep 14 14:08:36] NOTICE[5333] app_rpt.c: Tracepoint rpt_telemetry() exit
[Sep 14 14:08:36] NOTICE[5431] app_rpt.c: [1998] telemetry chan to teleconf
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** rx unkey
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** cos state unkey
[Sep 14 14:08:36] NOTICE[5335] app_rpt.c: **** rx un-key
[Sep 14 14:08:37] NOTICE[5335] app_rpt.c: **** rx key
[Sep 14 14:08:37] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 14:08:37] NOTICE[5335] app_rpt.c: **** rx key
[Sep 14 14:08:37] NOTICE[5333] app_rpt.c: Last = 1999, K
M: 2019-09-14 21:08:37.687 DMR Talker Alias (Data Format 1, Received 6/23 char): 'KI6ZHD'
M: 2019-09-14 21:08:38.378 DMR Talker Alias (Data Format 1, Received 13/23 char): 'KI6ZHD KI6ZHD'
M: 2019-09-14 21:08:38.839 DMR Slot 2, received network end of voice transmission, 1.9 seconds, 0% packet loss, BER: 0.0%
[Sep 14 14:08:39] NOTICE[5335] app_rpt.c: **** rx unkey
[Sep 14 14:08:39] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 14:08:39] NOTICE[5335] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 14:08:39] NOTICE[5335] app_rpt.c: **** cos state unkey
[Sep 14 14:08:39] NOTICE[5335] app_rpt.c: **** rx un-key
--


Btw.. I'm seeing a lot of error messages like the following today.  Are you aware of Brandmeister having issues today?
--
M: 2019-09-14 20:58:41.665 DMR, Logged into the master successfully
E: 2019-09-14 20:59:41.252 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 20:59:41.252 DMR, Closing DMR Network
M: 2019-09-14 20:59:41.252 DMR, Opening DMR Network
M: 2019-09-14 20:59:51.491 DMR, Logged into the master successfully
E: 2019-09-14 21:00:41.753 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:00:41.753 DMR, Closing DMR Network
M: 2019-09-14 21:00:41.753 DMR, Opening DMR Network
W: 2019-09-14 21:00:51.570 DMR, Login to the master has failed, retrying login ...
M: 2019-09-14 21:00:51.957 DMR, Logged into the master successfully
E: 2019-09-14 21:01:51.570 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:01:51.570 DMR, Closing DMR Network
M: 2019-09-14 21:01:51.570 DMR, Opening DMR Network
M: 2019-09-14 21:02:01.815 DMR, Logged into the master successfully
E: 2019-09-14 21:02:52.044 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:02:52.044 DMR, Closing DMR Network
M: 2019-09-14 21:02:52.044 DMR, Opening DMR Network
W: 2019-09-14 21:03:01.897 DMR, Login to the master has failed, retrying login ...
M: 2019-09-14 21:03:02.238 DMR, Logged into the master successfully
E: 2019-09-14 21:04:01.902 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:04:01.902 DMR, Closing DMR Network
M: 2019-09-14 21:04:01.903 DMR, Opening DMR Network
M: 2019-09-14 21:04:12.143 DMR, Logged into the master successfully
E: 2019-09-14 21:05:02.340 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:05:02.340 DMR, Closing DMR Network
M: 2019-09-14 21:05:02.340 DMR, Opening DMR Network
W: 2019-09-14 21:05:12.223 DMR, Login to the master has failed, retrying login ...
M: 2019-09-14 21:05:12.556 DMR, Logged into the master successfully
E: 2019-09-14 21:06:12.232 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:06:12.232 DMR, Closing DMR Network
M: 2019-09-14 21:06:12.232 DMR, Opening DMR Network
M: 2019-09-14 21:06:22.467 DMR, Logged into the master successfully
E: 2019-09-14 21:07:12.670 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:07:12.670 DMR, Closing DMR Network
M: 2019-09-14 21:07:12.670 DMR, Opening DMR Network
W: 2019-09-14 21:07:22.557 DMR, Login to the master has failed, retrying login ...
M: 2019-09-14 21:07:22.874 DMR, Logged into the master successfully
E: 2019-09-14 21:08:22.566 DMR, Connection to the master has timed out, retrying connection
M: 2019-09-14 21:08:22.567 DMR, Closing DMR Network
M: 2019-09-14 21:08:22.567 DMR, Opening DMR Network
M: 2019-09-14 21:08:32.791 DMR, Logged into the master successfully
--




On 09/14/2019 12:54 PM, Steve N4IRS wrote:
David,
It might be interesting to see you log when the issue happens. I am talking about a snip of the ASL, AB, MB logs at the point in time when the issue happens.

On 9/14/19 3:32 PM, David Ranch wrote:
Hello Everyone,

I have a working Brandmeister talk group to Analog Bridge setup working here but I see a PTT quirk that I'm curious if someone can comment on. 


If I key up the local DMR repeater that's in the Brandmeister talk group, this Raspberry Pi 3B+ setup using a URI-X sound card will light up the PTT LED on the URIX but then go off, then light back up and stay lit.  Beyond that, everything works as it should.  I see this behavior regardless if I use the SW IMBE vocoder or if I use a NW Digital THumbDV.  

Any thoughts on how to fix this "ACTIVE_FLAP" issue?

I've also attached a 7.7MB video of this behavior though I don't know if the Groups.IO system will accept it.  Let's see.

--David
KI6ZHD



Asterisk output with "rpt debug level 6"
--
*CLI> [Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:16] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:17] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:17] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:19645 rpt: **** rx unkey
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17387 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17406 cos_state_machine: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17467 cos_state_unkey: **** cos state unkey
[Sep 14 12:25:18] NOTICE[5335]: app_rpt.c:17473 cos_state_unkey: **** rx un-key
--


MMDVM STDOUT output
--
M: 2019-09-14 19:25:16.498 DMR Slot 2, received network voice header from KI6ZHD to TG WB5WXI
M: 2019-09-14 19:25:17.262 DMR Talker Alias (Data Format 1, Received 6/23 char): 'KI6ZHD'
D: 2019-09-14 19:25:17.789 DMR Slot 2, lost audio for 409ms filling in
M: 2019-09-14 19:25:17.980 DMR Talker Alias (Data Format 1, Received 13/23 char): 'KI6ZHD KI6ZHD'
M: 2019-09-14 19:25:18.461 DMR Slot 2, received network end of voice transmission, 2.3 seconds, 15% packet loss, BER: 0.0%
--


Analog_bridge STDOUT output:
--
[ Nothing shown after it starts up successfully ]
--





David Ranch
 


Hey Steve,

What I want to see is a snip from each log when receiving inbound traffic from DMR. The idea would be to see the start of transmission received on MB, the same signal passed to AB and finally to ASL.

That's what my first email showed.  It showed the Allstar, the MMDVM, and the Analog_bridge output.   Btw.. there is no Allstar here.. it's just going to Analog_Bridge as the audio will go to an analog repeater controller.

--David
KI6ZHD


Steve N4IRS
 

Hey David,
Quote
"It showed the Allstar, the MMDVM, and the Analog_bridge output."
"Btw.. there is no Allstar here"

So, which is it?

From your first message:
*CLI> [Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19522 rpt: **** rx key
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:17370 cos_state_machine: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 14 12:25:16] NOTICE[5335]: app_rpt.c:19532 rpt: **** rx key
[Sep 14 12:25:16] NOTICE[5333]: app_rpt.c:3502 __mklinklist: Last = 1999, K

If this is not ASL, what is it?

Yes, I know it's Asterisk and app_rpt

Without seeing the output from AB I don't see the data flow. From the AB systemd unit:
Environment=AnalogBridgeLogDir=/var/log/dvswitch/Analog_Bridge
This is the location of the AB log file.


On 9/14/19 6:55 PM, David Ranch wrote:

Hey Steve,

What I want to see is a snip from each log when receiving inbound traffic from DMR. The idea would be to see the start of transmission received on MB, the same signal passed to AB and finally to ASL.

That's what my first email showed.  It showed the Allstar, the MMDVM, and the Analog_bridge output.   Btw.. there is no Allstar here.. it's just going to Analog_Bridge as the audio will go to an analog repeater controller.

--David
KI6ZHD


David Ranch
 


Hello Steve,

"It showed the Allstar, the MMDVM, and the Analog_bridge output."
"Btw.. there is no Allstar here"

So, which is it?

Sorry, maybe I'm not using the right terminology here.  What I was meaning is that this system isn't on the Allstar network.  It's just a private node using Allstar version of Asterisk and DV-Switch's MMDVM and Analog_bridge.


Without seeing the output from AB I don't see the data flow. From the AB systemd unit:
Environment=AnalogBridgeLogDir=/var/log/dvswitch/Analog_Bridge
This is the location of the AB log file.

When I key up the DMR radio on the local repeater which is on the BM talk group, I then get the audio on this DV Switch setup.  I've included all the log lines I see when I key up the radio but there isn't anything showing on the Analog_bridge side when I do the key up.  Maybe I don't have enough debugging or logging enabled?  Below is the output when I start Analog_Bridge in non-daemon mode as well as it's config.  I'm happy to include any more details that you might need though I'm still new at this setup so I appreciate your patience.  :-)


----------------------------------------------------------------------------------------
[root@ww6bay-asl Analog_Bridge]# cat Analog_Bridge.ini                  
                                                                                                 

; Analog_Bridge configuration file.                                                                                                                                       
;   This file should be placed in either /etc or it can be                                                                                                                
;   supplied as a path on the Analog_Bridge command line.                                                                                                                 

; General Section describes settings for Analog_Bridge itself.
;   For RPI, don't forget to modprobe snd_pcm_oss to get the devices populated
[GENERAL]                                                                    
logLevel = 2                            ; Show messages and above 0=No logging, 1=Debug, 2=Message, 3=Info, 4=Warning, 5=Error, 6=Fatal

; Metadata management
exportMetadata = true                   ; Export metadata to USRP partner (transcode setups require this)
subscriberFile = /var/lib/dvswitch/subscriber_ids.csv   ; DMR ID to callsign lookup data                

; General vocoder setup information
;decoderFallBack = true                 ; Allow software AMBE decoding if a hardware decoder is not found
decoderFallBack = false                ; Allow software AMBE decoding if a hardware decoder is not found
useEmulator = false                     ; Use the MD380 AMBE emulator for AMBE72 (DMR/YSFN/NXDN)        
emulatorAddress = 127.0.0.1:2470        ; IP address and port of the server                             

; Audio devices are normally not needed when in gateway service.  These devices should
; only be set when local monitor or dongle modes are required.
outputAudioDevice = /dev/null           ; Audio device /dev/null, /dev/dsp1, portaudio, etc
inputAudioDevice = /dev/null            ; Only used for dongle mode

; Below values are for using this as a local DMR dongle (no ASL needed)
useMicrophone = false                   ; Use app as just a fancy dongle for DMR
useVox = false                          ; If using dongle mode (useMicrophone), enable VOX
voxDecay = 2                            ; Hang time in seconds
voxTrigger = 200                        ; Value to trip PTT (0-32767)

; Information for xx_Bridges (Where xx is MMDVM, HB, IPSC)
[AMBE_AUDIO]
server = 127.0.0.1                      ; IP address of xx_Bridge.py
fromDMRPort = 31100                     ; was rxport; AMBE frames from xx_Bridge (should match "toGatewayPort" in xx_Bridge.cfg)
toDMRPort = 31103                       ; was txport; AMBE frames from xx_Bridge (should match "fromGatewayPort" in xx_Bridge.cfg)
ambeMode = DMR                          ; DMR, DMR_IPSC, DSTAR, NXDN, P25, YSFN, YSFW
minTxTimeMS = 2000                      ; Minimum time in MS for hang delay
gatewayDmrId = 3107955                  ; ID the DMR listener will see when Allstar people speak; the repeater's main DMR ID; ID to use when transmitting from Analog_Bridge
repeaterID = 310795501                  ; private DMR ID used in the MMDVM bridge config; ID of source repeater
txTg = 31075                            ; TG to use for all frames received from Analog_Bridge -> xx_Bridge
txTs = 2                                ; Slot to use for frames received from Analog_Bridge -> xx_Bridge
colorCode = 1                           ; Color Code to assign DMR frames

; Information for USRP channel driver.  This interface uses PCM to transfer audio information
; There are two typical configurations, ASL and Transcode.  ASL (AllstarLink) is for analog clients connected
; to a digital network.  Transcode is when Analog_Bridge actually points its PCM interfaces back at itself,
; causing a TLV <-- (pcm <--> pcm) --> TLV type of architecture.
;   When using ASL, this matches the rpt.conf ASL file with a setting like:
;   rxchannel = usrp/127.0.0.1:34001:32001
;   When Transcoding, make toASLPort equal to the other instance fromASLPort (crossover)
[USRP]
server = 127.0.0.1                      ; IP address of Allstar/Asterisk
toASLPort = 32001                       ; Analog_Bridge <-- ASL
fromASLPort = 34001                     ; Analog_Bridge --> ASL
aslAudio = AUDIO_UNITY                  ; Audio to ASL (AUDIO_UNITY, AUDIO_USE_AGC, AUDIO_USE_GAIN)
agcGain = -20                           ; Gain (in db) of the AGC filter
dmrAudio = AUDIO_UNITY                  ; Audio from ASL (AUDIO_UNITY, AUDIO_USE_GAIN, AUDIO_BPF)
dmrGain = 0.35                          ; Gain factor of audio from ASL (0.0-1.0)

; Information for DV3000 hardware decoder
;   There are two configuration modes: IP (AMBEServer) and serial (direct connect hardware)
;   Use top server and port if using AMBEServer
;   Use bottom server/port and serial = true if using the DV3000u (thumbdv)
;   Did you run AMBETest4.py before using this?
[DV3000]
; server = 127.0.0.1                      ; IP address of AMBEServer
; port = 2460                             ; Port of AMBEServer
server = /dev/ttyUSB0                 ; Device of DV3000U on this machine
port = 460800                         ; Baud rate of the dongle
serial = true                         ; Use serial (DV3000U) or I

----------------------------------------------------------------------------------------



----------------------------------------------------------------------------------------
[root@ww6bay-asl log]# cat /var/log/Analog_Bridge.log                                                                                                                     
I: 2019-09-14 19:24:06.893 Analog Bridge Version 1.1 Wed  9 May 05:56:17 EDT 2018                                                                                         
I: 2019-09-14 19:24:06.893 Copyright (C) 2018 DVSwitch, INAD.                                                                                                             
I: 2019-09-14 19:24:06.893 Created by Mike N4IRR and Steve N4IRS                                                                                                          
I: 2019-09-14 19:24:06.893 Analog Bridge comes with ABSOLUTELY NO WARRANTY                                                                                                
I: 2019-09-14 19:24:06.893                                                                                                                                                
I: 2019-09-14 19:24:06.893 This software is for use on amateur radio networks only,                                                                                       
I: 2019-09-14 19:24:06.893 it is to be used for educational purposes only. Its use on                                                                                     
I: 2019-09-14 19:24:06.893 commercial networks is strictly prohibited.
I: 2019-09-14 19:24:06.893
I: 2019-09-14 19:24:06.894 Analog Bridge is starting
M: 2019-09-14 19:24:06.894 Setting [GENERAL] logLevel -> 2
M: 2019-09-14 19:24:06.894 Setting [GENERAL] exportMetadata -> true
M: 2019-09-14 19:24:06.894 Setting [GENERAL] subscriberFile -> /var/lib/dvswitch/subscriber_ids.csv
M: 2019-09-14 19:24:06.894 Setting [GENERAL] decoderFallBack -> false
M: 2019-09-14 19:24:06.894 Setting [GENERAL] useEmulator -> false
M: 2019-09-14 19:24:06.894 Setting [GENERAL] emulatorAddress -> 127.0.0.1:2470
M: 2019-09-14 19:24:06.894 Setting [GENERAL] outputAudioDevice -> /dev/null
M: 2019-09-14 19:24:06.894 Setting [GENERAL] inputAudioDevice -> /dev/null
M: 2019-09-14 19:24:06.894 Setting [GENERAL] useMicrophone -> false
M: 2019-09-14 19:24:06.894 Setting [GENERAL] useVox -> false
M: 2019-09-14 19:24:06.894 Setting [GENERAL] voxDecay -> 2
M: 2019-09-14 19:24:06.894 Setting [GENERAL] voxTrigger -> 200
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] server -> 127.0.0.1
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] fromDMRPort -> 31100
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] toDMRPort -> 31103
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] ambeMode -> DMR
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] minTxTimeMS -> 2000
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] gatewayDmrId -> 3107955
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] repeaterID -> 310795501
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] txTg -> 31075
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] txTs -> 2
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] colorCode -> 1
M: 2019-09-14 19:24:06.894 Setting [USRP] server -> 127.0.0.1
M: 2019-09-14 19:24:06.894 Setting [USRP] toASLPort -> 32001
M: 2019-09-14 19:24:06.894 Setting [USRP] fromASLPort -> 34001
M: 2019-09-14 19:24:06.894 Setting [USRP] aslAudio -> AUDIO_UNITY
M: 2019-09-14 19:24:06.894 Setting [USRP] agcGain -> -20
M: 2019-09-14 19:24:06.894 Setting [USRP] dmrAudio -> AUDIO_UNITY
M: 2019-09-14 19:24:06.894 Setting [USRP] dmrGain -> 0.35
M: 2019-09-14 19:24:06.894 Setting [DV3000] server -> /dev/ttyUSB0
M: 2019-09-14 19:24:06.894 Setting [DV3000] port -> 460800
M: 2019-09-14 19:24:06.894 Setting [DV3000] serial -> true
W: 2019-09-14 19:24:06.895 ioctl reset error
W: 2019-09-14 19:24:06.895 ioctl speed error
W: 2019-09-14 19:24:06.895 ioctl stereo error
W: 2019-09-14 19:24:06.895 ioctl setfmt error
M: 2019-09-14 19:24:06.895 Audio In/Out Device: /dev/null
I: 2019-09-14 19:24:06.895 Open UDP listener on 127.0.0.1:31100
I: 2019-09-14 19:24:06.895 Open USRP on 127.0.0.1:32001
M: 2019-09-14 19:24:06.895 Connecting to DV3000 hardware......
M: 2019-09-14 19:24:06.906 Begin DV3000 decode
I: 2019-09-14 19:24:06.906 Subscriber IDs loaded: 0
I: 2019-09-14 19:24:06.906 Default extended metadata <3107955>
I: 2019-09-14 19:24:06.906 Using hardware AMBE vocoder
I: 2019-09-14 19:24:06.907 Starting Analog_Bridge --> USRP thread
I: 2019-09-14 19:24:06.907 Open UDP listener on 127.0.0.1:34001
I: 2019-09-14 19:24:06.907 Starting USRP --> HB_Bridge thread
----------------------------------------------------------------------------------------

I don't see any more output from the Analog_Bridge log file after this.

--David





On 9/14/19 6:55 PM, David Ranch wrote:

Hey Steve,

What I want to see is a snip from each log when receiving inbound traffic from DMR. The idea would be to see the start of transmission received on MB, the same signal passed to AB and finally to ASL.

That's what my first email showed.  It showed the Allstar, the MMDVM, and the Analog_bridge output.   Btw.. there is no Allstar here.. it's just going to Analog_Bridge as the audio will go to an analog repeater controller.

--David
KI6ZHD



Steve N4IRS
 

There should be output from AB.
Change in AB.ini:
logLevel = 2
To:
logLevel = 1

Without seeing the traffic in AB there is no way to troubleshoot.

On 9/14/19 8:49 PM, David Ranch wrote:

Hello Steve,

"It showed the Allstar, the MMDVM, and the Analog_bridge output."
"Btw.. there is no Allstar here"

So, which is it?

Sorry, maybe I'm not using the right terminology here.  What I was meaning is that this system isn't on the Allstar network.  It's just a private node using Allstar version of Asterisk and DV-Switch's MMDVM and Analog_bridge.


Without seeing the output from AB I don't see the data flow. From the AB systemd unit:
Environment=AnalogBridgeLogDir=/var/log/dvswitch/Analog_Bridge
This is the location of the AB log file.

When I key up the DMR radio on the local repeater which is on the BM talk group, I then get the audio on this DV Switch setup.  I've included all the log lines I see when I key up the radio but there isn't anything showing on the Analog_bridge side when I do the key up.  Maybe I don't have enough debugging or logging enabled?  Below is the output when I start Analog_Bridge in non-daemon mode as well as it's config.  I'm happy to include any more details that you might need though I'm still new at this setup so I appreciate your patience.  :-)


----------------------------------------------------------------------------------------
[root@ww6bay-asl Analog_Bridge]# cat Analog_Bridge.ini                  
                                                                                                 

; Analog_Bridge configuration file.                                                                                                                                       
;   This file should be placed in either /etc or it can be                                                                                                                
;   supplied as a path on the Analog_Bridge command line.                                                                                                                 

; General Section describes settings for Analog_Bridge itself.
;   For RPI, don't forget to modprobe snd_pcm_oss to get the devices populated
[GENERAL]                                                                    
logLevel = 2                            ; Show messages and above 0=No logging, 1=Debug, 2=Message, 3=Info, 4=Warning, 5=Error, 6=Fatal

; Metadata management
exportMetadata = true                   ; Export metadata to USRP partner (transcode setups require this)
subscriberFile = /var/lib/dvswitch/subscriber_ids.csv   ; DMR ID to callsign lookup data                

; General vocoder setup information
;decoderFallBack = true                 ; Allow software AMBE decoding if a hardware decoder is not found
decoderFallBack = false                ; Allow software AMBE decoding if a hardware decoder is not found
useEmulator = false                     ; Use the MD380 AMBE emulator for AMBE72 (DMR/YSFN/NXDN)        
emulatorAddress = 127.0.0.1:2470        ; IP address and port of the server                             

; Audio devices are normally not needed when in gateway service.  These devices should
; only be set when local monitor or dongle modes are required.
outputAudioDevice = /dev/null           ; Audio device /dev/null, /dev/dsp1, portaudio, etc
inputAudioDevice = /dev/null            ; Only used for dongle mode

; Below values are for using this as a local DMR dongle (no ASL needed)
useMicrophone = false                   ; Use app as just a fancy dongle for DMR
useVox = false                          ; If using dongle mode (useMicrophone), enable VOX
voxDecay = 2                            ; Hang time in seconds
voxTrigger = 200                        ; Value to trip PTT (0-32767)

; Information for xx_Bridges (Where xx is MMDVM, HB, IPSC)
[AMBE_AUDIO]
server = 127.0.0.1                      ; IP address of xx_Bridge.py
fromDMRPort = 31100                     ; was rxport; AMBE frames from xx_Bridge (should match "toGatewayPort" in xx_Bridge.cfg)
toDMRPort = 31103                       ; was txport; AMBE frames from xx_Bridge (should match "fromGatewayPort" in xx_Bridge.cfg)
ambeMode = DMR                          ; DMR, DMR_IPSC, DSTAR, NXDN, P25, YSFN, YSFW
minTxTimeMS = 2000                      ; Minimum time in MS for hang delay
gatewayDmrId = 3107955                  ; ID the DMR listener will see when Allstar people speak; the repeater's main DMR ID; ID to use when transmitting from Analog_Bridge
repeaterID = 310795501                  ; private DMR ID used in the MMDVM bridge config; ID of source repeater
txTg = 31075                            ; TG to use for all frames received from Analog_Bridge -> xx_Bridge
txTs = 2                                ; Slot to use for frames received from Analog_Bridge -> xx_Bridge
colorCode = 1                           ; Color Code to assign DMR frames

; Information for USRP channel driver.  This interface uses PCM to transfer audio information
; There are two typical configurations, ASL and Transcode.  ASL (AllstarLink) is for analog clients connected
; to a digital network.  Transcode is when Analog_Bridge actually points its PCM interfaces back at itself,
; causing a TLV <-- (pcm <--> pcm) --> TLV type of architecture.
;   When using ASL, this matches the rpt.conf ASL file with a setting like:
;   rxchannel = usrp/127.0.0.1:34001:32001
;   When Transcoding, make toASLPort equal to the other instance fromASLPort (crossover)
[USRP]
server = 127.0.0.1                      ; IP address of Allstar/Asterisk
toASLPort = 32001                       ; Analog_Bridge <-- ASL
fromASLPort = 34001                     ; Analog_Bridge --> ASL
aslAudio = AUDIO_UNITY                  ; Audio to ASL (AUDIO_UNITY, AUDIO_USE_AGC, AUDIO_USE_GAIN)
agcGain = -20                           ; Gain (in db) of the AGC filter
dmrAudio = AUDIO_UNITY                  ; Audio from ASL (AUDIO_UNITY, AUDIO_USE_GAIN, AUDIO_BPF)
dmrGain = 0.35                          ; Gain factor of audio from ASL (0.0-1.0)

; Information for DV3000 hardware decoder
;   There are two configuration modes: IP (AMBEServer) and serial (direct connect hardware)
;   Use top server and port if using AMBEServer
;   Use bottom server/port and serial = true if using the DV3000u (thumbdv)
;   Did you run AMBETest4.py before using this?
[DV3000]
; server = 127.0.0.1                      ; IP address of AMBEServer
; port = 2460                             ; Port of AMBEServer
server = /dev/ttyUSB0                 ; Device of DV3000U on this machine
port = 460800                         ; Baud rate of the dongle
serial = true                         ; Use serial (DV3000U) or I

----------------------------------------------------------------------------------------



----------------------------------------------------------------------------------------
[root@ww6bay-asl log]# cat /var/log/Analog_Bridge.log                                                                                                                     
I: 2019-09-14 19:24:06.893 Analog Bridge Version 1.1 Wed  9 May 05:56:17 EDT 2018                                                                                         
I: 2019-09-14 19:24:06.893 Copyright (C) 2018 DVSwitch, INAD.                                                                                                             
I: 2019-09-14 19:24:06.893 Created by Mike N4IRR and Steve N4IRS                                                                                                          
I: 2019-09-14 19:24:06.893 Analog Bridge comes with ABSOLUTELY NO WARRANTY                                                                                                
I: 2019-09-14 19:24:06.893                                                                                                                                                
I: 2019-09-14 19:24:06.893 This software is for use on amateur radio networks only,                                                                                       
I: 2019-09-14 19:24:06.893 it is to be used for educational purposes only. Its use on                                                                                     
I: 2019-09-14 19:24:06.893 commercial networks is strictly prohibited.
I: 2019-09-14 19:24:06.893
I: 2019-09-14 19:24:06.894 Analog Bridge is starting
M: 2019-09-14 19:24:06.894 Setting [GENERAL] logLevel -> 2
M: 2019-09-14 19:24:06.894 Setting [GENERAL] exportMetadata -> true
M: 2019-09-14 19:24:06.894 Setting [GENERAL] subscriberFile -> /var/lib/dvswitch/subscriber_ids.csv
M: 2019-09-14 19:24:06.894 Setting [GENERAL] decoderFallBack -> false
M: 2019-09-14 19:24:06.894 Setting [GENERAL] useEmulator -> false
M: 2019-09-14 19:24:06.894 Setting [GENERAL] emulatorAddress -> 127.0.0.1:2470
M: 2019-09-14 19:24:06.894 Setting [GENERAL] outputAudioDevice -> /dev/null
M: 2019-09-14 19:24:06.894 Setting [GENERAL] inputAudioDevice -> /dev/null
M: 2019-09-14 19:24:06.894 Setting [GENERAL] useMicrophone -> false
M: 2019-09-14 19:24:06.894 Setting [GENERAL] useVox -> false
M: 2019-09-14 19:24:06.894 Setting [GENERAL] voxDecay -> 2
M: 2019-09-14 19:24:06.894 Setting [GENERAL] voxTrigger -> 200
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] server -> 127.0.0.1
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] fromDMRPort -> 31100
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] toDMRPort -> 31103
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] ambeMode -> DMR
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] minTxTimeMS -> 2000
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] gatewayDmrId -> 3107955
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] repeaterID -> 310795501
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] txTg -> 31075
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] txTs -> 2
M: 2019-09-14 19:24:06.894 Setting [AMBE_AUDIO] colorCode -> 1
M: 2019-09-14 19:24:06.894 Setting [USRP] server -> 127.0.0.1
M: 2019-09-14 19:24:06.894 Setting [USRP] toASLPort -> 32001
M: 2019-09-14 19:24:06.894 Setting [USRP] fromASLPort -> 34001
M: 2019-09-14 19:24:06.894 Setting [USRP] aslAudio -> AUDIO_UNITY
M: 2019-09-14 19:24:06.894 Setting [USRP] agcGain -> -20
M: 2019-09-14 19:24:06.894 Setting [USRP] dmrAudio -> AUDIO_UNITY
M: 2019-09-14 19:24:06.894 Setting [USRP] dmrGain -> 0.35
M: 2019-09-14 19:24:06.894 Setting [DV3000] server -> /dev/ttyUSB0
M: 2019-09-14 19:24:06.894 Setting [DV3000] port -> 460800
M: 2019-09-14 19:24:06.894 Setting [DV3000] serial -> true
W: 2019-09-14 19:24:06.895 ioctl reset error
W: 2019-09-14 19:24:06.895 ioctl speed error
W: 2019-09-14 19:24:06.895 ioctl stereo error
W: 2019-09-14 19:24:06.895 ioctl setfmt error
M: 2019-09-14 19:24:06.895 Audio In/Out Device: /dev/null
I: 2019-09-14 19:24:06.895 Open UDP listener on 127.0.0.1:31100
I: 2019-09-14 19:24:06.895 Open USRP on 127.0.0.1:32001
M: 2019-09-14 19:24:06.895 Connecting to DV3000 hardware......
M: 2019-09-14 19:24:06.906 Begin DV3000 decode
I: 2019-09-14 19:24:06.906 Subscriber IDs loaded: 0
I: 2019-09-14 19:24:06.906 Default extended metadata <3107955>
I: 2019-09-14 19:24:06.906 Using hardware AMBE vocoder
I: 2019-09-14 19:24:06.907 Starting Analog_Bridge --> USRP thread
I: 2019-09-14 19:24:06.907 Open UDP listener on 127.0.0.1:34001
I: 2019-09-14 19:24:06.907 Starting USRP --> HB_Bridge thread
----------------------------------------------------------------------------------------

I don't see any more output from the Analog_Bridge log file after this.

--David





On 9/14/19 6:55 PM, David Ranch wrote:

Hey Steve,

What I want to see is a snip from each log when receiving inbound traffic from DMR. The idea would be to see the start of transmission received on MB, the same signal passed to AB and finally to ASL.

That's what my first email showed.  It showed the Allstar, the MMDVM, and the Analog_bridge output.   Btw.. there is no Allstar here.. it's just going to Analog_Bridge as the audio will go to an analog repeater controller.

--David
KI6ZHD




David Ranch
 


Hey Steve,

There should be output from AB.
Change in AB.ini:
logLevel = 2
To:
logLevel = 1

Without seeing the traffic in AB there is no way to troubleshoot.

Ok, I made the requested change (it shows it as being set to "1") but I don't see any additional logging when I key up on the DMR radio be it in the STDOUT or in the /var/log/Analog_Bridge.log.  Here is the AB startup output:
--
[root@ww6bay-asl bin]# vim /opt/Analog_Bridge/Analog_Bridge.ini
[root@ww6bay-asl bin]#  /opt/Analog_Bridge/Analog_Bridge /opt/Analog_Bridge/Analog_Bridge.ini
I: 2019-09-15 01:50:06.281 Analog Bridge Version 1.1 Wed  9 May 05:56:17 EDT 2018           
I: 2019-09-15 01:50:06.281 Copyright (C) 2018 DVSwitch, INAD.                               
I: 2019-09-15 01:50:06.281 Created by Mike N4IRR and Steve N4IRS                            
I: 2019-09-15 01:50:06.281 Analog Bridge comes with ABSOLUTELY NO WARRANTY                  
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 This software is for use on amateur radio networks only,         
I: 2019-09-15 01:50:06.281 it is to be used for educational purposes only. Its use on       
I: 2019-09-15 01:50:06.281 commercial networks is strictly prohibited.                      
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 Analog Bridge is starting                                        
M: 2019-09-15 01:50:06.281 Setting [GENERAL] logLevel -> 1                                  
M: 2019-09-15 01:50:06.281 Setting [GENERAL] exportMetadata -> true                         
M: 2019-09-15 01:50:06.281 Setting [GENERAL] subscriberFile -> /var/lib/dvswitch/subscriber_ids.csv
M: 2019-09-15 01:50:06.281 Setting [GENERAL] decoderFallBack -> false                             
M: 2019-09-15 01:50:06.281 Setting [GENERAL] useEmulator -> false                                 
M: 2019-09-15 01:50:06.281 Setting [GENERAL] emulatorAddress -> 127.0.0.1:2470                    
M: 2019-09-15 01:50:06.282 Setting [GENERAL] outputAudioDevice -> /dev/null                       
M: 2019-09-15 01:50:06.282 Setting [GENERAL] inputAudioDevice -> /dev/null                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useMicrophone -> false                               
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useVox -> false                                      
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxDecay -> 2                                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxTrigger -> 200                                    
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] server -> 127.0.0.1                               
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] fromDMRPort -> 31100                              
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] toDMRPort -> 31103
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] ambeMode -> DMR
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] minTxTimeMS -> 2000
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] gatewayDmrId -> 3107955
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] repeaterID -> 310795501
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTg -> 31075
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTs -> 2
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] colorCode -> 1
M: 2019-09-15 01:50:06.282 Setting [USRP] server -> 127.0.0.1
M: 2019-09-15 01:50:06.282 Setting [USRP] toASLPort -> 32001
M: 2019-09-15 01:50:06.282 Setting [USRP] fromASLPort -> 34001
M: 2019-09-15 01:50:06.282 Setting [USRP] aslAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] agcGain -> -20
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrGain -> 0.35
M: 2019-09-15 01:50:06.282 Setting [DV3000] server -> /dev/ttyUSB0
M: 2019-09-15 01:50:06.282 Setting [DV3000] port -> 460800
M: 2019-09-15 01:50:06.282 Setting [DV3000] serial -> true
W: 2019-09-15 01:50:06.282 ioctl reset error
W: 2019-09-15 01:50:06.282 ioctl speed error
W: 2019-09-15 01:50:06.282 ioctl stereo error
W: 2019-09-15 01:50:06.282 ioctl setfmt error
M: 2019-09-15 01:50:06.282 Audio In/Out Device: /dev/null
I: 2019-09-15 01:50:06.282 Open UDP listener on 127.0.0.1:31100
I: 2019-09-15 01:50:06.282 Open USRP on 127.0.0.1:32001
D: 2019-09-15 01:50:06.282 Attempt to  init DV3000 in DMR mode
M: 2019-09-15 01:50:06.282 Connecting to DV3000 hardware......
M: 2019-09-15 01:50:06.294 Begin DV3000 decode
Project 25 IMBE Encoder/Decoder Fixed-Point implementation
Developed by Pavel Yazev E-mail: pyazev@...
Version 1.0 (c) Copyright 2009
This program comes with ABSOLUTELY NO WARRANTY.
This is free software, and you are welcome to redistribute it
under certain conditions; see the file ``LICENSE'' for details.
I: 2019-09-15 01:50:06.295 Subscriber IDs loaded: 0
I: 2019-09-15 01:50:06.295 Default extended metadata <3107955>
I: 2019-09-15 01:50:06.296 Using hardware AMBE vocoder
I: 2019-09-15 01:50:06.296 Starting Analog_Bridge --> USRP thread
I: 2019-09-15 01:50:06.296 Open UDP listener on 127.0.0.1:34001
I: 2019-09-15 01:50:06.297 Starting USRP --> HB_Bridge thread
--

No more STDOUT after this point. Any other ideas?   This is on a Raspberry Pi 3B+ and I can install other binaries if required.



Steve N4IRS
 

Run AB in the foreground and capture the output.

As I said before:
This is from /lib/systemd/system/analog_bridge.service

Environment=AnalogBridgeLogDir=/var/log/dvswitch/Analog_Bridge

This is the location of the AB log file.

On 9/14/19 9:55 PM, David Ranch wrote:

Hey Steve,

There should be output from AB.
Change in AB.ini:
logLevel = 2
To:
logLevel = 1

Without seeing the traffic in AB there is no way to troubleshoot.

Ok, I made the requested change (it shows it as being set to "1") but I don't see any additional logging when I key up on the DMR radio be it in the STDOUT or in the /var/log/Analog_Bridge.log.  Here is the AB startup output:
--
[root@ww6bay-asl bin]# vim /opt/Analog_Bridge/Analog_Bridge.ini
[root@ww6bay-asl bin]#  /opt/Analog_Bridge/Analog_Bridge /opt/Analog_Bridge/Analog_Bridge.ini
I: 2019-09-15 01:50:06.281 Analog Bridge Version 1.1 Wed  9 May 05:56:17 EDT 2018           
I: 2019-09-15 01:50:06.281 Copyright (C) 2018 DVSwitch, INAD.                               
I: 2019-09-15 01:50:06.281 Created by Mike N4IRR and Steve N4IRS                            
I: 2019-09-15 01:50:06.281 Analog Bridge comes with ABSOLUTELY NO WARRANTY                  
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 This software is for use on amateur radio networks only,         
I: 2019-09-15 01:50:06.281 it is to be used for educational purposes only. Its use on       
I: 2019-09-15 01:50:06.281 commercial networks is strictly prohibited.                      
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 Analog Bridge is starting                                        
M: 2019-09-15 01:50:06.281 Setting [GENERAL] logLevel -> 1                                  
M: 2019-09-15 01:50:06.281 Setting [GENERAL] exportMetadata -> true                         
M: 2019-09-15 01:50:06.281 Setting [GENERAL] subscriberFile -> /var/lib/dvswitch/subscriber_ids.csv
M: 2019-09-15 01:50:06.281 Setting [GENERAL] decoderFallBack -> false                             
M: 2019-09-15 01:50:06.281 Setting [GENERAL] useEmulator -> false                                 
M: 2019-09-15 01:50:06.281 Setting [GENERAL] emulatorAddress -> 127.0.0.1:2470                    
M: 2019-09-15 01:50:06.282 Setting [GENERAL] outputAudioDevice -> /dev/null                       
M: 2019-09-15 01:50:06.282 Setting [GENERAL] inputAudioDevice -> /dev/null                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useMicrophone -> false                               
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useVox -> false                                      
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxDecay -> 2                                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxTrigger -> 200                                    
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] server -> 127.0.0.1                               
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] fromDMRPort -> 31100                              
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] toDMRPort -> 31103
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] ambeMode -> DMR
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] minTxTimeMS -> 2000
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] gatewayDmrId -> 3107955
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] repeaterID -> 310795501
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTg -> 31075
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTs -> 2
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] colorCode -> 1
M: 2019-09-15 01:50:06.282 Setting [USRP] server -> 127.0.0.1
M: 2019-09-15 01:50:06.282 Setting [USRP] toASLPort -> 32001
M: 2019-09-15 01:50:06.282 Setting [USRP] fromASLPort -> 34001
M: 2019-09-15 01:50:06.282 Setting [USRP] aslAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] agcGain -> -20
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrGain -> 0.35
M: 2019-09-15 01:50:06.282 Setting [DV3000] server -> /dev/ttyUSB0
M: 2019-09-15 01:50:06.282 Setting [DV3000] port -> 460800
M: 2019-09-15 01:50:06.282 Setting [DV3000] serial -> true
W: 2019-09-15 01:50:06.282 ioctl reset error
W: 2019-09-15 01:50:06.282 ioctl speed error
W: 2019-09-15 01:50:06.282 ioctl stereo error
W: 2019-09-15 01:50:06.282 ioctl setfmt error
M: 2019-09-15 01:50:06.282 Audio In/Out Device: /dev/null
I: 2019-09-15 01:50:06.282 Open UDP listener on 127.0.0.1:31100
I: 2019-09-15 01:50:06.282 Open USRP on 127.0.0.1:32001
D: 2019-09-15 01:50:06.282 Attempt to  init DV3000 in DMR mode
M: 2019-09-15 01:50:06.282 Connecting to DV3000 hardware......
M: 2019-09-15 01:50:06.294 Begin DV3000 decode
Project 25 IMBE Encoder/Decoder Fixed-Point implementation
Developed by Pavel Yazev E-mail: pyazev@...
Version 1.0 (c) Copyright 2009
This program comes with ABSOLUTELY NO WARRANTY.
This is free software, and you are welcome to redistribute it
under certain conditions; see the file ``LICENSE'' for details.
I: 2019-09-15 01:50:06.295 Subscriber IDs loaded: 0
I: 2019-09-15 01:50:06.295 Default extended metadata <3107955>
I: 2019-09-15 01:50:06.296 Using hardware AMBE vocoder
I: 2019-09-15 01:50:06.296 Starting Analog_Bridge --> USRP thread
I: 2019-09-15 01:50:06.296 Open UDP listener on 127.0.0.1:34001
I: 2019-09-15 01:50:06.297 Starting USRP --> HB_Bridge thread
--

No more STDOUT after this point. Any other ideas?   This is on a Raspberry Pi 3B+ and I can install other binaries if required.




David Ranch
 


Hello Steve,

Run AB in the foreground and capture the output.

I believe I am running in the foreground here unless I'm missing some additional command line option?
--
[root@ww6bay-asl bin]#  /opt/Analog_Bridge/Analog_Bridge /opt/Analog_Bridge/Analog_Bridge.ini
I: 2019-09-15 01:50:06.281 Analog Bridge Version 1.1 Wed  9 May 05:56:17 EDT 2018           
I: 2019-09-15 01:50:06.281 Copyright (C) 2018 DVSwitch, INAD.                               
I: 2019-09-15 01:50:06.281 Created by Mike N4IRR and Steve N4IRS                            
I: 2019-09-15 01:50:06.281 Analog Bridge comes with ABSOLUTELY NO WARRANTY                  
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 This software is for use on amateur radio networks only,         
I: 2019-09-15 01:50:06.281 it is to be used for educational purposes only. Its use on       
I: 2019-09-15 01:50:06.281 commercial networks is strictly prohibited.                      
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 Analog Bridge is starting                                        
M: 2019-09-15 01:50:06.281 Setting [GENERAL] logLevel -> 1                                  
M: 2019-09-15 01:50:06.281 Setting [GENERAL] exportMetadata -> true                         
M: 2019-09-15 01:50:06.281 Setting [GENERAL] subscriberFile -> /var/lib/dvswitch/subscriber_ids.csv
M: 2019-09-15 01:50:06.281 Setting [GENERAL] decoderFallBack -> false                             
M: 2019-09-15 01:50:06.281 Setting [GENERAL] useEmulator -> false                                 
M: 2019-09-15 01:50:06.281 Setting [GENERAL] emulatorAddress -> 127.0.0.1:2470                    
M: 2019-09-15 01:50:06.282 Setting [GENERAL] outputAudioDevice -> /dev/null                       
M: 2019-09-15 01:50:06.282 Setting [GENERAL] inputAudioDevice -> /dev/null                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useMicrophone -> false                               
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useVox -> false                                      
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxDecay -> 2                                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxTrigger -> 200                                    
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] server -> 127.0.0.1                               
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] fromDMRPort -> 31100                              
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] toDMRPort -> 31103                                
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] ambeMode -> DMR
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] minTxTimeMS -> 2000
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] gatewayDmrId -> 3107955
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] repeaterID -> 310795501
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTg -> 31075
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTs -> 2
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] colorCode -> 1
M: 2019-09-15 01:50:06.282 Setting [USRP] server -> 127.0.0.1
M: 2019-09-15 01:50:06.282 Setting [USRP] toASLPort -> 32001
M: 2019-09-15 01:50:06.282 Setting [USRP] fromASLPort -> 34001
M: 2019-09-15 01:50:06.282 Setting [USRP] aslAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] agcGain -> -20
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrGain -> 0.35
M: 2019-09-15 01:50:06.282 Setting [DV3000] server -> /dev/ttyUSB0
M: 2019-09-15 01:50:06.282 Setting [DV3000] port -> 460800
M: 2019-09-15 01:50:06.282 Setting [DV3000] serial -> true
W: 2019-09-15 01:50:06.282 ioctl reset error
W: 2019-09-15 01:50:06.282 ioctl speed error
W: 2019-09-15 01:50:06.282 ioctl stereo error
W: 2019-09-15 01:50:06.282 ioctl setfmt error
M: 2019-09-15 01:50:06.282 Audio In/Out Device: /dev/null
I: 2019-09-15 01:50:06.282 Open UDP listener on 127.0.0.1:31100
I: 2019-09-15 01:50:06.282 Open USRP on 127.0.0.1:32001
D: 2019-09-15 01:50:06.282 Attempt to  init DV3000 in DMR mode
M: 2019-09-15 01:50:06.282 Connecting to DV3000 hardware......
M: 2019-09-15 01:50:06.294 Begin DV3000 decode
Project 25 IMBE Encoder/Decoder Fixed-Point implementation
Developed by Pavel Yazev E-mail: pyazev@...
Version 1.0 (c) Copyright 2009
This program comes with ABSOLUTELY NO WARRANTY.
This is free software, and you are welcome to redistribute it
under certain conditions; see the file ``LICENSE'' for details.
I: 2019-09-15 01:50:06.295 Subscriber IDs loaded: 0
I: 2019-09-15 01:50:06.295 Default extended metadata <3107955>
I: 2019-09-15 01:50:06.296 Using hardware AMBE vocoder
I: 2019-09-15 01:50:06.296 Starting Analog_Bridge --> USRP thread
I: 2019-09-15 01:50:06.296 Open UDP listener on 127.0.0.1:34001
I: 2019-09-15 01:50:06.297 Starting USRP --> HB_Bridge thread





^C
[root@ww6bay-asl bin]#
--




As I said before:
This is from /lib/systemd/system/analog_bridge.service

Environment=AnalogBridgeLogDir=/var/log/dvswitch/Analog_Bridge

This is the location of the AB log file.

On 9/14/19 9:55 PM, David Ranch wrote:

Hey Steve,

There should be output from AB.
Change in AB.ini:
logLevel = 2
To:
logLevel = 1

Without seeing the traffic in AB there is no way to troubleshoot.

Ok, I made the requested change (it shows it as being set to "1") but I don't see any additional logging when I key up on the DMR radio be it in the STDOUT or in the /var/log/Analog_Bridge.log.  Here is the AB startup output:
--
[root@ww6bay-asl bin]# vim /opt/Analog_Bridge/Analog_Bridge.ini
[root@ww6bay-asl bin]#  /opt/Analog_Bridge/Analog_Bridge /opt/Analog_Bridge/Analog_Bridge.ini
I: 2019-09-15 01:50:06.281 Analog Bridge Version 1.1 Wed  9 May 05:56:17 EDT 2018           
I: 2019-09-15 01:50:06.281 Copyright (C) 2018 DVSwitch, INAD.                               
I: 2019-09-15 01:50:06.281 Created by Mike N4IRR and Steve N4IRS                            
I: 2019-09-15 01:50:06.281 Analog Bridge comes with ABSOLUTELY NO WARRANTY                  
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 This software is for use on amateur radio networks only,         
I: 2019-09-15 01:50:06.281 it is to be used for educational purposes only. Its use on       
I: 2019-09-15 01:50:06.281 commercial networks is strictly prohibited.                      
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 Analog Bridge is starting                                        
M: 2019-09-15 01:50:06.281 Setting [GENERAL] logLevel -> 1                                  
M: 2019-09-15 01:50:06.281 Setting [GENERAL] exportMetadata -> true                         
M: 2019-09-15 01:50:06.281 Setting [GENERAL] subscriberFile -> /var/lib/dvswitch/subscriber_ids.csv
M: 2019-09-15 01:50:06.281 Setting [GENERAL] decoderFallBack -> false                             
M: 2019-09-15 01:50:06.281 Setting [GENERAL] useEmulator -> false                                 
M: 2019-09-15 01:50:06.281 Setting [GENERAL] emulatorAddress -> 127.0.0.1:2470                    
M: 2019-09-15 01:50:06.282 Setting [GENERAL] outputAudioDevice -> /dev/null                       
M: 2019-09-15 01:50:06.282 Setting [GENERAL] inputAudioDevice -> /dev/null                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useMicrophone -> false                               
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useVox -> false                                      
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxDecay -> 2                                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxTrigger -> 200                                    
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] server -> 127.0.0.1                               
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] fromDMRPort -> 31100                              
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] toDMRPort -> 31103
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] ambeMode -> DMR
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] minTxTimeMS -> 2000
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] gatewayDmrId -> 3107955
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] repeaterID -> 310795501
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTg -> 31075
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTs -> 2
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] colorCode -> 1
M: 2019-09-15 01:50:06.282 Setting [USRP] server -> 127.0.0.1
M: 2019-09-15 01:50:06.282 Setting [USRP] toASLPort -> 32001
M: 2019-09-15 01:50:06.282 Setting [USRP] fromASLPort -> 34001
M: 2019-09-15 01:50:06.282 Setting [USRP] aslAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] agcGain -> -20
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrGain -> 0.35
M: 2019-09-15 01:50:06.282 Setting [DV3000] server -> /dev/ttyUSB0
M: 2019-09-15 01:50:06.282 Setting [DV3000] port -> 460800
M: 2019-09-15 01:50:06.282 Setting [DV3000] serial -> true
W: 2019-09-15 01:50:06.282 ioctl reset error
W: 2019-09-15 01:50:06.282 ioctl speed error
W: 2019-09-15 01:50:06.282 ioctl stereo error
W: 2019-09-15 01:50:06.282 ioctl setfmt error
M: 2019-09-15 01:50:06.282 Audio In/Out Device: /dev/null
I: 2019-09-15 01:50:06.282 Open UDP listener on 127.0.0.1:31100
I: 2019-09-15 01:50:06.282 Open USRP on 127.0.0.1:32001
D: 2019-09-15 01:50:06.282 Attempt to  init DV3000 in DMR mode
M: 2019-09-15 01:50:06.282 Connecting to DV3000 hardware......
M: 2019-09-15 01:50:06.294 Begin DV3000 decode
Project 25 IMBE Encoder/Decoder Fixed-Point implementation
Developed by Pavel Yazev E-mail: pyazev@...
Version 1.0 (c) Copyright 2009
This program comes with ABSOLUTELY NO WARRANTY.
This is free software, and you are welcome to redistribute it
under certain conditions; see the file ``LICENSE'' for details.
I: 2019-09-15 01:50:06.295 Subscriber IDs loaded: 0
I: 2019-09-15 01:50:06.295 Default extended metadata <3107955>
I: 2019-09-15 01:50:06.296 Using hardware AMBE vocoder
I: 2019-09-15 01:50:06.296 Starting Analog_Bridge --> USRP thread
I: 2019-09-15 01:50:06.296 Open UDP listener on 127.0.0.1:34001
I: 2019-09-15 01:50:06.297 Starting USRP --> HB_Bridge thread
--

No more STDOUT after this point. Any other ideas?   This is on a Raspberry Pi 3B+ and I can install other binaries if required.





Steve N4IRS
 

You are. When you transmit from analog or DMR, nothing is logged by AB? You do hear the traffic at the "other end" ?

On 9/14/19 11:50 PM, David Ranch wrote:

Hello Steve,

Run AB in the foreground and capture the output.

I believe I am running in the foreground here unless I'm missing some additional command line option?
--
[root@ww6bay-asl bin]#  /opt/Analog_Bridge/Analog_Bridge /opt/Analog_Bridge/Analog_Bridge.ini
I: 2019-09-15 01:50:06.281 Analog Bridge Version 1.1 Wed  9 May 05:56:17 EDT 2018           
I: 2019-09-15 01:50:06.281 Copyright (C) 2018 DVSwitch, INAD.                               
I: 2019-09-15 01:50:06.281 Created by Mike N4IRR and Steve N4IRS                            
I: 2019-09-15 01:50:06.281 Analog Bridge comes with ABSOLUTELY NO WARRANTY                  
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 This software is for use on amateur radio networks only,         
I: 2019-09-15 01:50:06.281 it is to be used for educational purposes only. Its use on       
I: 2019-09-15 01:50:06.281 commercial networks is strictly prohibited.                      
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 Analog Bridge is starting                                        
M: 2019-09-15 01:50:06.281 Setting [GENERAL] logLevel -> 1                                  
M: 2019-09-15 01:50:06.281 Setting [GENERAL] exportMetadata -> true                         
M: 2019-09-15 01:50:06.281 Setting [GENERAL] subscriberFile -> /var/lib/dvswitch/subscriber_ids.csv
M: 2019-09-15 01:50:06.281 Setting [GENERAL] decoderFallBack -> false                             
M: 2019-09-15 01:50:06.281 Setting [GENERAL] useEmulator -> false                                 
M: 2019-09-15 01:50:06.281 Setting [GENERAL] emulatorAddress -> 127.0.0.1:2470                    
M: 2019-09-15 01:50:06.282 Setting [GENERAL] outputAudioDevice -> /dev/null                       
M: 2019-09-15 01:50:06.282 Setting [GENERAL] inputAudioDevice -> /dev/null                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useMicrophone -> false                               
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useVox -> false                                      
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxDecay -> 2                                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxTrigger -> 200                                    
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] server -> 127.0.0.1                               
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] fromDMRPort -> 31100                              
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] toDMRPort -> 31103                                
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] ambeMode -> DMR
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] minTxTimeMS -> 2000
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] gatewayDmrId -> 3107955
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] repeaterID -> 310795501
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTg -> 31075
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTs -> 2
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] colorCode -> 1
M: 2019-09-15 01:50:06.282 Setting [USRP] server -> 127.0.0.1
M: 2019-09-15 01:50:06.282 Setting [USRP] toASLPort -> 32001
M: 2019-09-15 01:50:06.282 Setting [USRP] fromASLPort -> 34001
M: 2019-09-15 01:50:06.282 Setting [USRP] aslAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] agcGain -> -20
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrGain -> 0.35
M: 2019-09-15 01:50:06.282 Setting [DV3000] server -> /dev/ttyUSB0
M: 2019-09-15 01:50:06.282 Setting [DV3000] port -> 460800
M: 2019-09-15 01:50:06.282 Setting [DV3000] serial -> true
W: 2019-09-15 01:50:06.282 ioctl reset error
W: 2019-09-15 01:50:06.282 ioctl speed error
W: 2019-09-15 01:50:06.282 ioctl stereo error
W: 2019-09-15 01:50:06.282 ioctl setfmt error
M: 2019-09-15 01:50:06.282 Audio In/Out Device: /dev/null
I: 2019-09-15 01:50:06.282 Open UDP listener on 127.0.0.1:31100
I: 2019-09-15 01:50:06.282 Open USRP on 127.0.0.1:32001
D: 2019-09-15 01:50:06.282 Attempt to  init DV3000 in DMR mode
M: 2019-09-15 01:50:06.282 Connecting to DV3000 hardware......
M: 2019-09-15 01:50:06.294 Begin DV3000 decode
Project 25 IMBE Encoder/Decoder Fixed-Point implementation
Developed by Pavel Yazev E-mail: pyazev@...
Version 1.0 (c) Copyright 2009
This program comes with ABSOLUTELY NO WARRANTY.
This is free software, and you are welcome to redistribute it
under certain conditions; see the file ``LICENSE'' for details.
I: 2019-09-15 01:50:06.295 Subscriber IDs loaded: 0
I: 2019-09-15 01:50:06.295 Default extended metadata <3107955>
I: 2019-09-15 01:50:06.296 Using hardware AMBE vocoder
I: 2019-09-15 01:50:06.296 Starting Analog_Bridge --> USRP thread
I: 2019-09-15 01:50:06.296 Open UDP listener on 127.0.0.1:34001
I: 2019-09-15 01:50:06.297 Starting USRP --> HB_Bridge thread





^C
[root@ww6bay-asl bin]#
--




As I said before:
This is from /lib/systemd/system/analog_bridge.service

Environment=AnalogBridgeLogDir=/var/log/dvswitch/Analog_Bridge

This is the location of the AB log file.

On 9/14/19 9:55 PM, David Ranch wrote:

Hey Steve,

There should be output from AB.
Change in AB.ini:
logLevel = 2
To:
logLevel = 1

Without seeing the traffic in AB there is no way to troubleshoot.

Ok, I made the requested change (it shows it as being set to "1") but I don't see any additional logging when I key up on the DMR radio be it in the STDOUT or in the /var/log/Analog_Bridge.log.  Here is the AB startup output:
--
[root@ww6bay-asl bin]# vim /opt/Analog_Bridge/Analog_Bridge.ini
[root@ww6bay-asl bin]#  /opt/Analog_Bridge/Analog_Bridge /opt/Analog_Bridge/Analog_Bridge.ini
I: 2019-09-15 01:50:06.281 Analog Bridge Version 1.1 Wed  9 May 05:56:17 EDT 2018           
I: 2019-09-15 01:50:06.281 Copyright (C) 2018 DVSwitch, INAD.                               
I: 2019-09-15 01:50:06.281 Created by Mike N4IRR and Steve N4IRS                            
I: 2019-09-15 01:50:06.281 Analog Bridge comes with ABSOLUTELY NO WARRANTY                  
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 This software is for use on amateur radio networks only,         
I: 2019-09-15 01:50:06.281 it is to be used for educational purposes only. Its use on       
I: 2019-09-15 01:50:06.281 commercial networks is strictly prohibited.                      
I: 2019-09-15 01:50:06.281                                                                  
I: 2019-09-15 01:50:06.281 Analog Bridge is starting                                        
M: 2019-09-15 01:50:06.281 Setting [GENERAL] logLevel -> 1                                  
M: 2019-09-15 01:50:06.281 Setting [GENERAL] exportMetadata -> true                         
M: 2019-09-15 01:50:06.281 Setting [GENERAL] subscriberFile -> /var/lib/dvswitch/subscriber_ids.csv
M: 2019-09-15 01:50:06.281 Setting [GENERAL] decoderFallBack -> false                             
M: 2019-09-15 01:50:06.281 Setting [GENERAL] useEmulator -> false                                 
M: 2019-09-15 01:50:06.281 Setting [GENERAL] emulatorAddress -> 127.0.0.1:2470                    
M: 2019-09-15 01:50:06.282 Setting [GENERAL] outputAudioDevice -> /dev/null                       
M: 2019-09-15 01:50:06.282 Setting [GENERAL] inputAudioDevice -> /dev/null                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useMicrophone -> false                               
M: 2019-09-15 01:50:06.282 Setting [GENERAL] useVox -> false                                      
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxDecay -> 2                                        
M: 2019-09-15 01:50:06.282 Setting [GENERAL] voxTrigger -> 200                                    
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] server -> 127.0.0.1                               
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] fromDMRPort -> 31100                              
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] toDMRPort -> 31103
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] ambeMode -> DMR
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] minTxTimeMS -> 2000
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] gatewayDmrId -> 3107955
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] repeaterID -> 310795501
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTg -> 31075
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] txTs -> 2
M: 2019-09-15 01:50:06.282 Setting [AMBE_AUDIO] colorCode -> 1
M: 2019-09-15 01:50:06.282 Setting [USRP] server -> 127.0.0.1
M: 2019-09-15 01:50:06.282 Setting [USRP] toASLPort -> 32001
M: 2019-09-15 01:50:06.282 Setting [USRP] fromASLPort -> 34001
M: 2019-09-15 01:50:06.282 Setting [USRP] aslAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] agcGain -> -20
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrAudio -> AUDIO_UNITY
M: 2019-09-15 01:50:06.282 Setting [USRP] dmrGain -> 0.35
M: 2019-09-15 01:50:06.282 Setting [DV3000] server -> /dev/ttyUSB0
M: 2019-09-15 01:50:06.282 Setting [DV3000] port -> 460800
M: 2019-09-15 01:50:06.282 Setting [DV3000] serial -> true
W: 2019-09-15 01:50:06.282 ioctl reset error
W: 2019-09-15 01:50:06.282 ioctl speed error
W: 2019-09-15 01:50:06.282 ioctl stereo error
W: 2019-09-15 01:50:06.282 ioctl setfmt error
M: 2019-09-15 01:50:06.282 Audio In/Out Device: /dev/null
I: 2019-09-15 01:50:06.282 Open UDP listener on 127.0.0.1:31100
I: 2019-09-15 01:50:06.282 Open USRP on 127.0.0.1:32001
D: 2019-09-15 01:50:06.282 Attempt to  init DV3000 in DMR mode
M: 2019-09-15 01:50:06.282 Connecting to DV3000 hardware......
M: 2019-09-15 01:50:06.294 Begin DV3000 decode
Project 25 IMBE Encoder/Decoder Fixed-Point implementation
Developed by Pavel Yazev E-mail: pyazev@...
Version 1.0 (c) Copyright 2009
This program comes with ABSOLUTELY NO WARRANTY.
This is free software, and you are welcome to redistribute it
under certain conditions; see the file ``LICENSE'' for details.
I: 2019-09-15 01:50:06.295 Subscriber IDs loaded: 0
I: 2019-09-15 01:50:06.295 Default extended metadata <3107955>
I: 2019-09-15 01:50:06.296 Using hardware AMBE vocoder
I: 2019-09-15 01:50:06.296 Starting Analog_Bridge --> USRP thread
I: 2019-09-15 01:50:06.296 Open UDP listener on 127.0.0.1:34001
I: 2019-09-15 01:50:06.297 Starting USRP --> HB_Bridge thread
--

No more STDOUT after this point. Any other ideas?   This is on a Raspberry Pi 3B+ and I can install other binaries if required.






David Ranch
 

Hello Steve,

You are. When you transmit from analog or DMR, nothing is logged by AB? You do hear the traffic at the "other end" ?

When I transmit from the DMR HT, nothing is logged but I do in fact hear my voice just fine from the speaker connected to the URI-X soundcard.   This setup is essentially:

   DMR HT --> Motorola DMR repeater --> Brandmeister TG --> DV-Switch MMDVM --> DV-Switch Analog_Bridge --> URI-X --> analog speaker


This DV-Switch setup currently a receive only but once it's put on the hill, it will support transmitting.  If it would help, I can try to hack something together if you wish to try the reverse path.

--David
KI6ZHD


Steve N4IRS
 

David,
BTW, you left out ASL in your diagram.
Let's make sure you are are running what we think you are running. Do this:
delete the log file
date
/opt/Analog_Bridge/Analog_Bridge /opt/Analog_Bridge/Analog_Bridge.ini

Then post the output from date and AB.

Steve

On 9/15/19 5:54 PM, David Ranch wrote:
Hello Steve,

You are. When you transmit from analog or DMR, nothing is logged by AB? You do hear the traffic at the "other end" ?

When I transmit from the DMR HT, nothing is logged but I do in fact hear my voice just fine from the speaker connected to the URI-X soundcard.   This setup is essentially:

   DMR HT --> Motorola DMR repeater --> Brandmeister TG --> DV-Switch MMDVM --> DV-Switch Analog_Bridge --> URI-X --> analog speaker


This DV-Switch setup currently a receive only but once it's put on the hill, it will support transmitting.  If it would help, I can try to hack something together if you wish to try the reverse path.

--David
KI6ZHD



David Ranch
 


Hello Steve,

BTW, you left out ASL in your diagram.

Ah.. ok.  Should it be the following?

   DMR HT --> Motorola DMR repeater --> Brandmeister TG --> DV-Switch MMDVM --> ASL --> DV-Switch Analog_Bridge --> URI-X --> analog speaker



Let's make sure you are are running what we think you are running. Do this:
delete the log file
date
/opt/Analog_Bridge/Analog_Bridge /opt/Analog_Bridge/Analog_Bridge.ini

Then post the output from date and AB.


Ok, as requested, I shut everything down, moved all three log files, started things up in non-daemon mode, then keyed up the DMR HT.  I then also keyed up on the analog side and I could hear the DMR repeater key up and give a hum sound (no microphone was connected.. I asserted the signal via the URI-X's "CTCSS/PL" line.  Regardless of either type of key up, it didn't create any log entries in the Analog_Bridge.log file



[root@ww6bay-asl log]# date
Sun Sep 15 21:37:05 PDT 2019

[root@ww6bay-asl log]# mv asterisk/messages asterisk/messages.orig                                                                                                                             
'asterisk/messages' -> 'asterisk/messages.orig' 

[root@ww6bay-asl log]# mv MMDVM_Bridge-2019-09-15.log MMDVM_Bridge-2019-09-15.log.orig                                                                                                         
'MMDVM_Bridge-2019-09-15.log' -> 'MMDVM_Bridge-2019-09-15.log.orig'  

[root@ww6bay-asl log]# mv Analog_Bridge.log Analog_Bridge.log.orig
'Analog_Bridge.log' -> 'Analog_Bridge.log.orig'




[root@ww6bay-asl log]# ls -la *
-rw-r--r-- 1 root root   3658 Sep 15 21:32 Analog_Bridge.log  <------------------------------------------------
-rw-r--r-- 1 root root  14569 Sep 14 20:42 Analog_Bridge.log.1
-rw-r--r-- 1 root root   8209 Sep 14 22:18 Analog_Bridge.log.2.gz
-rw-r--r-- 1 root root      0 Sep 15 00:00 Analog_Bridge.log.orig
-rw-r--r-- 1 root root  50319 Sep  8 16:57 MMDVM_Bridge-2019-09-08.log
-rw-r--r-- 1 root root  46397 Sep  9 16:33 MMDVM_Bridge-2019-09-09.log
-rw-r--r-- 1 root root  40802 Sep 10 13:29 MMDVM_Bridge-2019-09-10.log
-rw-r--r-- 1 root root  60824 Sep 11 16:03 MMDVM_Bridge-2019-09-11.log
-rw-r--r-- 1 root root  70045 Sep 12 16:43 MMDVM_Bridge-2019-09-12.log
-rw-r--r-- 1 root root  89382 Sep 13 16:57 MMDVM_Bridge-2019-09-13.log
-rw-r--r-- 1 root root  99209 Sep 14 16:59 MMDVM_Bridge-2019-09-14.log
-rw-r--r-- 1 root root 443294 Sep 15 16:59 MMDVM_Bridge-2019-09-15.log.orig
-rw-r--r-- 1 root root  87698 Sep 15 21:37 MMDVM_Bridge-2019-09-16.log
-rw------- 1 root utmp      0 Feb 22  2016 btmp
-rw------- 1 root root  24024 Sep 15 15:15 faillog
-rw-r--r-- 1 root root 292292 Sep 15 15:15 lastlog
-rw-rw-r-- 1 root utmp   3072 Sep 15 15:15 wtmp

asterisk:
total 624
drwxr-xr-x 2 root root    140 Sep 15 21:32 .
drwxr-xr-x 4 root root    420 Sep 15 21:32 ..
-rw-r--r-- 1 root root 485619 Sep 15 03:37 astdb.txt
-rw-r--r-- 1 root root      0 Sep  7 17:52 event_log
-rw-r--r-- 1 root root  10011 Sep 15 21:37 messages
-rw-r--r-- 1 root root 133569 Sep 15 21:30 messages.orig
-rw-r--r-- 1 root root    152 Sep 15 21:32 queue_log

httpd:
total 8
drwxr-xr-x 2 root root 120 Sep  8 00:00 .
drwxr-xr-x 4 root root 420 Sep 15 21:32 ..
-rw-r--r-- 1 root root   0 Sep  8 00:00 access_log
-rw-r--r-- 1 root root   0 Sep  7 17:52 access_log.1
-rw-r--r-- 1 root root 372 Sep  8 00:00 error_log
-rw-r--r-- 1 root root 488 Sep  8 00:00 error_log.1


[root@ww6bay-asl log]# cat asterisk/messages                                                      
[Sep 15 21:32:11] NOTICE[6380] dnsmgr.c: Managed DNS entries will be refreshed every 300 seconds. 
[Sep 15 21:32:11] NOTICE[6380] cdr.c: CDR simple logging enabled.                                 
[Sep 15 21:32:11] NOTICE[6380] loader.c: 118 modules will be loaded.                              
[Sep 15 21:32:12] WARNING[6380] cdr_odbc.c: cdr_odbc: Unable to load config for ODBC CDR's: cdr_odbc.conf
[Sep 15 21:32:12] ERROR[6380] chan_voter.c: Unable to load config voter.conf                            
[Sep 15 21:32:12] NOTICE[6380] chan_irlp.c: Unable to load config irlp.conf                             
[Sep 15 21:32:12] ERROR[6380] codec_dahdi.c: Failed to open /dev/dahdi/transcode: No such file or directory
[Sep 15 21:32:12] NOTICE[6380] chan_usbradio.c: Unable to load config usbradio.conf                       
[Sep 15 21:32:12] WARNING[6380] pbx_spool.c: Unable to create queue directory /var/spool/asterisk/outgoing -- outgoing spool disabled
[Sep 15 21:32:12] ERROR[6380] chan_echolink.c: Unable to load config echolink.conf                                                  
[Sep 15 21:32:12] NOTICE[6380] pbx_ael.c: Starting AEL load process.                                                                
[Sep 15 21:32:12] NOTICE[6380] pbx_ael.c: AEL load process: calculated config file name '/usr/local/hamvoip-asterisk/etc/asterisk/extensions.ael'.
[Sep 15 21:32:12] NOTICE[6380] pbx_ael.c: File /usr/local/hamvoip-asterisk/etc/asterisk/extensions.ael not found; AEL declining load             
[Sep 15 21:32:12] NOTICE[6387] app_rpt.c: Normal Repeater Init  1998                                                                             
[Sep 15 21:32:12] NOTICE[6387] app_rpt.c: Normal Repeater Init  1999                                                                             
[Sep 15 21:32:12] NOTICE[6403] chan_simpleusb.c: Assigned USB device 1-1.4:1.0 to simpleusb channel usb                                          
[Sep 15 21:32:12] NOTICE[6403] chan_simpleusb.c: Loaded parameters from simpleusb_tune_usb.conf for device usb .                                 
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: cancel_pfxfone!![Sep 15 21:32:22] NOTICE[6402] app_rpt.c: cancel_pfxfone!![Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Starting node lookup: My node number: 1998, seeking node = 1                                                                                                                                                                                                   
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Not found in nodes stanza: node = 1                                                                                                                                 
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: cancel_pfxfone!![Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Starting node lookup: My node number: 1998, seeking node = 19                                             
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Not found in nodes stanza: node = 19                                                                                                                                
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: cancel_pfxfone!![Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Starting node lookup: My node number: 1998, seeking node = 199                                            
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Not found in nodes stanza: node = 199                                                                                                                               
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: cancel_pfxfone!![Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Starting node lookup: My node number: 1998, seeking node = 1999                                           
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Found node via nodes stanza: node = 1999, val = radio@....0.1/1999,NONE                                                                                           
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Connect attempt to node 1999                                                                                                                                        
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Mode: Transceive                                                                                                                                                    
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Connection type: Normal                                                                                                                                             
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: rpt (remote) initiating call to IAX2/radio@....0.1:4569/1999 on IAX2/127.0.0.1:4569-2968                                                                          
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=3                                                                                                                           
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=46                                                                                                                          
[Sep 15 21:32:22] NOTICE[6407] app_rpt.c: Starting node lookup: My node number: 1999, seeking node = 1998                                                                                                     
[Sep 15 21:32:22] NOTICE[6407] app_rpt.c: Found node via nodes stanza: node = 1998, val = radio@....0.1:4569/1998,NONE                                                                                      
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=9                                                                                                                           
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=46                                                                                                                          
[Sep 15 21:32:22] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() entered mode=46                                                                                                                          
[Sep 15 21:32:22] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() entered mode=46                                                                                                                          
[Sep 15 21:32:42] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=0                                                                                                                           
[Sep 15 21:32:42] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() exit                                                                                                                                     
[Sep 15 21:32:42] NOTICE[6412] app_rpt.c: [1998] telemetry chan to teleconf                                                                                                                                   
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2                                                               
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx unkey                                                                                                                                                       
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2                                                       
[Sep 15 21:33:14] NOTICE[6402] app_rpt.c: Last = 1999, K                                                                                                                                                      
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5                                                           
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** cos state unkey                                                                                                                                                
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx un-key                                                                                                                                                      
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2                                                               
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:14] NOTICE[6402] app_rpt.c: Last = 1999, K                                                                                                                                                      
[Sep 15 21:33:15] NOTICE[6402] app_rpt.c: TIMER is out of bounds! elap = 31                                                                                                                                   
[Sep 15 21:33:16] NOTICE[6404] app_rpt.c: **** rx unkey                                                                                                                                                       
[Sep 15 21:33:16] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2                                                       
[Sep 15 21:33:16] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5                                                           
[Sep 15 21:33:16] NOTICE[6404] app_rpt.c: **** cos state unkey                                                                                                                                                
[Sep 15 21:33:16] NOTICE[6404] app_rpt.c: **** rx un-key                                                                                                                                                      
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2                                                               
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx unkey                                                                                                                                                       
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 15 21:33:46] NOTICE[6402] app_rpt.c: Last = 1999, K
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** cos state unkey
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx un-key
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx key
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx key
[Sep 15 21:33:46] NOTICE[6402] app_rpt.c: Last = 1999, K
[Sep 15 21:33:48] NOTICE[6404] app_rpt.c: **** rx unkey
[Sep 15 21:33:48] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 15 21:33:48] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 15 21:33:48] NOTICE[6404] app_rpt.c: **** cos state unkey
[Sep 15 21:33:48] NOTICE[6404] app_rpt.c: **** rx un-key
[Sep 15 21:33:59] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:33:59] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 15 21:33:59] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:34:00] NOTICE[6404] app_rpt.c: Last = 1998, K
[Sep 15 21:34:00] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() entered mode=0
[Sep 15 21:34:00] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() exit
[Sep 15 21:34:00] NOTICE[6413] app_rpt.c: [1999] telemetry chan to teleconf
[Sep 15 21:34:07] NOTICE[6402] app_rpt.c: **** rx unkey
[Sep 15 21:34:07] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 15 21:34:07] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 15 21:34:07] NOTICE[6402] app_rpt.c: **** cos state unkey
[Sep 15 21:34:07] NOTICE[6402] app_rpt.c: **** rx un-key
[Sep 15 21:34:26] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:34:26] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 15 21:34:26] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:34:26] NOTICE[6404] app_rpt.c: Last = 1998, K
[Sep 15 21:34:31] NOTICE[6402] app_rpt.c: **** rx unkey
[Sep 15 21:34:31] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 15 21:34:31] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 15 21:34:31] NOTICE[6402] app_rpt.c: **** cos state unkey
[Sep 15 21:34:31] NOTICE[6402] app_rpt.c: **** rx un-key
[Sep 15 21:34:45] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:34:45] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 15 21:34:45] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:34:45] NOTICE[6404] app_rpt.c: Last = 1998, K
[Sep 15 21:34:48] NOTICE[6402] app_rpt.c: **** rx unkey
[Sep 15 21:34:48] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 15 21:34:48] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 15 21:34:48] NOTICE[6402] app_rpt.c: **** cos state unkey
[Sep 15 21:34:48] NOTICE[6402] app_rpt.c: **** rx un-key
[Sep 15 21:37:42] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=0
[Sep 15 21:37:42] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() exit
[Sep 15 21:37:42] NOTICE[6420] app_rpt.c: [1998] telemetry chan to teleconf
[Sep 15 21:39:00] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() entered mode=0
[Sep 15 21:39:00] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() exit
[Sep 15 21:39:00] NOTICE[6423] app_rpt.c: [1999] telemetry chan to teleconf




[root@ww6bay-asl log]# cat Analog_Bridge.log   
I: 2019-09-16 04:32:27.676 Analog Bridge Version 1.1 Wed  9 May 05:56:17 EDT 2018
I: 2019-09-16 04:32:27.677 Copyright (C) 2018 DVSwitch, INAD.                   
I: 2019-09-16 04:32:27.677 Created by Mike N4IRR and Steve N4IRS                
I: 2019-09-16 04:32:27.677 Analog Bridge comes with ABSOLUTELY NO WARRANTY      
I: 2019-09-16 04:32:27.677                                                      
I: 2019-09-16 04:32:27.677 This software is for use on amateur radio networks only,
I: 2019-09-16 04:32:27.677 it is to be used for educational purposes only. Its use on
I: 2019-09-16 04:32:27.677 commercial networks is strictly prohibited.              
I: 2019-09-16 04:32:27.677
I: 2019-09-16 04:32:27.677 Analog Bridge is starting
M: 2019-09-16 04:32:27.677 Setting [GENERAL] logLevel -> 1   <-------------------------------------------------------
M: 2019-09-16 04:32:27.677 Setting [GENERAL] exportMetadata -> true
M: 2019-09-16 04:32:27.677 Setting [GENERAL] subscriberFile -> /var/lib/dvswitch/subscriber_ids.csv
M: 2019-09-16 04:32:27.678 Setting [GENERAL] decoderFallBack -> false
M: 2019-09-16 04:32:27.678 Setting [GENERAL] useEmulator -> false
M: 2019-09-16 04:32:27.678 Setting [GENERAL] emulatorAddress -> 127.0.0.1:2470
M: 2019-09-16 04:32:27.678 Setting [GENERAL] outputAudioDevice -> /dev/null
M: 2019-09-16 04:32:27.678 Setting [GENERAL] inputAudioDevice -> /dev/null
M: 2019-09-16 04:32:27.678 Setting [GENERAL] useMicrophone -> false
M: 2019-09-16 04:32:27.678 Setting [GENERAL] useVox -> false
M: 2019-09-16 04:32:27.678 Setting [GENERAL] voxDecay -> 2
M: 2019-09-16 04:32:27.678 Setting [GENERAL] voxTrigger -> 200
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] server -> 127.0.0.1
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] fromDMRPort -> 31100
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] toDMRPort -> 31103
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] ambeMode -> DMR
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] minTxTimeMS -> 2000
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] gatewayDmrId -> 3107955
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] repeaterID -> 310795501
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] txTg -> 31075
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] txTs -> 2
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] colorCode -> 1
M: 2019-09-16 04:32:27.678 Setting [USRP] server -> 127.0.0.1
M: 2019-09-16 04:32:27.678 Setting [USRP] toASLPort -> 32001
M: 2019-09-16 04:32:27.678 Setting [USRP] fromASLPort -> 34001
M: 2019-09-16 04:32:27.678 Setting [USRP] aslAudio -> AUDIO_UNITY
M: 2019-09-16 04:32:27.679 Setting [USRP] agcGain -> -20
M: 2019-09-16 04:32:27.679 Setting [USRP] dmrAudio -> AUDIO_UNITY
M: 2019-09-16 04:32:27.679 Setting [USRP] dmrGain -> 0.35
M: 2019-09-16 04:32:27.679 Setting [DV3000] server -> /dev/ttyUSB0
M: 2019-09-16 04:32:27.679 Setting [DV3000] port -> 460800
M: 2019-09-16 04:32:27.679 Setting [DV3000] serial -> true
W: 2019-09-16 04:32:27.679 ioctl reset error
W: 2019-09-16 04:32:27.679 ioctl speed error
W: 2019-09-16 04:32:27.679 ioctl stereo error
W: 2019-09-16 04:32:27.679 ioctl setfmt error
M: 2019-09-16 04:32:27.679 Audio In/Out Device: /dev/null
I: 2019-09-16 04:32:27.679 Open UDP listener on 127.0.0.1:31100
I: 2019-09-16 04:32:27.679 Open USRP on 127.0.0.1:32001
D: 2019-09-16 04:32:27.679 Attempt to  init DV3000 in DMR mode
M: 2019-09-16 04:32:27.679 Connecting to DV3000 hardware......
M: 2019-09-16 04:32:27.691 Begin DV3000 decode
I: 2019-09-16 04:32:27.692 Subscriber IDs loaded: 0
I: 2019-09-16 04:32:27.692 Default extended metadata <3107955>
I: 2019-09-16 04:32:27.692 Using hardware AMBE vocoder
I: 2019-09-16 04:32:27.692 Starting Analog_Bridge --> USRP thread
I: 2019-09-16 04:32:27.692 Open UDP listener on 127.0.0.1:34001
I: 2019-09-16 04:32:27.692 Starting USRP --> HB_Bridge thread



[root@ww6bay-asl log]# cat MMDVM_Bridge-2019-09-16.log

I: 2019-09-16 04:32:19.066 MMDVM_Bridge:                                                                                                                                                                      
I: 2019-09-16 04:32:19.066 Portions Copyright (C) 2018 DVSwitch, INAD.                                                                                                                                        
I: 2019-09-16 04:32:19.066 Hacks by Mike N4IRR and Steve N4IRS                                                                                                                                                
I: 2019-09-16 04:32:19.066 =-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-                                                                                                                                       
I: 2019-09-16 04:32:19.066 This software is for use on amateur radio networks only,                                                                                                                           
I: 2019-09-16 04:32:19.066 it is to be used for educational purposes only. Its use on                                                                                                                         
I: 2019-09-16 04:32:19.066 commercial networks is strictly prohibited.                                                                                                                                        
I: 2019-09-16 04:32:19.066 Copyright(C) 2015-2018 by Jonathan Naylor, G4KLX and others                                                                                                                        
M: 2019-09-16 04:32:19.066 MMDVM_Bridge-20180423 is starting                                                                                                                                                  
M: 2019-09-16 04:32:19.066 Built 19:26:19 Jul 26 2018 (GitID #903ae69)                                                                                                                                        
I: 2019-09-16 04:32:19.066 General Parameters                                                                                                                                                                 
I: 2019-09-16 04:32:19.066     Callsign:                                                                                                                                                                      
I: 2019-09-16 04:32:19.066     Id: 310795501                                                                                                                                                                  
I: 2019-09-16 04:32:19.066     Duplex: no                                                                                                                                                                     
I: 2019-09-16 04:32:19.066     Timeout: 180s                                                                                                                                                                  
I: 2019-09-16 04:32:19.066     D-Star: disabled                                                                                                                                                               
I: 2019-09-16 04:32:19.067     DMR: enabled                                                                                                                                                                   
I: 2019-09-16 04:32:19.067     YSF: disabled                                                                                                                                                                  
I: 2019-09-16 04:32:19.067     P25: disabled                                                                                                                                                                  
I: 2019-09-16 04:32:19.067     NXDN: disabled                                                                                                                                                                 
I: 2019-09-16 04:32:19.067 Modem Parameters                                                                                                                                                                   
I: 2019-09-16 04:32:19.067     Port: /dev/null                                                                                                                                                                
I: 2019-09-16 04:32:19.067     RX Invert: no                                                                                                                                                                  
I: 2019-09-16 04:32:19.067     TX Invert: no                                                                                                                                                                  
I: 2019-09-16 04:32:19.067     PTT Invert: no                                                                                                                                                                 
I: 2019-09-16 04:32:19.067     TX Delay: 100ms                                                                                                                                                                
I: 2019-09-16 04:32:19.067     RX Offset: 0Hz                                                                                                                                                                 
I: 2019-09-16 04:32:19.067     TX Offset: 0Hz                                                                                                                                                                 
I: 2019-09-16 04:32:19.067     RX DC Offset: 0                                                                                                                                                                
I: 2019-09-16 04:32:19.067     TX DC Offset: 0                                                                                                                                                                
I: 2019-09-16 04:32:19.067     RF Level: 100.0%                                                                                                                                                               
I: 2019-09-16 04:32:19.067     DMR Delay: 0 (0.0ms)                                                                                                                                                           
I: 2019-09-16 04:32:19.067     RX Level: 50.0%                                                                                                                                                                
I: 2019-09-16 04:32:19.067     CW Id TX Level: 50.0%                                                                                                                                                          
I: 2019-09-16 04:32:19.067     D-Star TX Level: 50.0%                                                                                                                                                         
I: 2019-09-16 04:32:19.067     DMR TX Level: 50.0%                                                                                                                                                            
I: 2019-09-16 04:32:19.067     YSF TX Level: 50.0%                                                                                                                                                            
I: 2019-09-16 04:32:19.067     P25 TX Level: 50.0%                                                                                                                                                            
I: 2019-09-16 04:32:19.067     NXDN TX Level: 50.0%                                                                                                                                                           
I: 2019-09-16 04:32:19.067     RX Frequency: 222340000Hz (222340000Hz)                                                                                                                                        
I: 2019-09-16 04:32:19.067     TX Frequency: 224940000Hz (224940000Hz)                                                                                                                                        
M: 2019-09-16 04:32:19.068 Opening the MMDVM                                                                                                                                                                  
I: 2019-09-16 04:32:19.068 Display Parameters                                                                                                                                                                 
I: 2019-09-16 04:32:19.068     Type:                                                                                                                                                                          
I: 2019-09-16 04:32:19.068 DMR Network Parameters                                                                                                                                                             
I: 2019-09-16 04:32:19.068     Address: 3108.repeater.net                                                                                                                                                     
I: 2019-09-16 04:32:19.068     Port: 62031                                                                                                                                                                    
I: 2019-09-16 04:32:19.068     Local: 62032                                                                                                                                                                   
I: 2019-09-16 04:32:19.068     Jitter: 360ms                                                                                                                                                                  
I: 2019-09-16 04:32:19.068     Slot 1: disabled                                                                                                                                                               
I: 2019-09-16 04:32:19.068     Slot 2: enabled                                                                                                                                                                
I: 2019-09-16 04:32:19.068     Mode Hang: 3s                                                                                                                                                                  
I: 2019-09-16 04:32:19.176 Info Parameters                                                                                                                                                                    
I: 2019-09-16 04:32:19.176     Callsign:                                                                                                                                                                      
I: 2019-09-16 04:32:19.177     RX Frequency: 222340000Hz                                                                                                                                                      
I: 2019-09-16 04:32:19.177     TX Frequency: 224940000Hz                                                                                                                                                      
I: 2019-09-16 04:32:19.177     Power: 1W                                                                                                                                                                      
I: 2019-09-16 04:32:19.177     Latitude: 41.733299deg N                                                                                                                                                       
I: 2019-09-16 04:32:19.177     Longitude: -50.399899deg E                                                                                                                                                     
I: 2019-09-16 04:32:19.177     Height: 0m                                                                                                                                                                     
I: 2019-09-16 04:32:19.177     Location: "Iceberg, North Atlantic"                                                                                                                                            
I: 2019-09-16 04:32:19.177     Description: "123cba"                                                                                                                                                          
I: 2019-09-16 04:32:19.177     URL: "https://groups.io/g/DVSwitch"                                                                                                                                            
M: 2019-09-16 04:32:19.177 DMR, Opening DMR Network                                                                                                                                                           
I: 2019-09-16 04:32:19.177 RSSI                                                                                                                                                                               
I: 2019-09-16 04:32:19.177     Mapping File: RSSI.dat                                                                                                                                                         
W: 2019-09-16 04:32:19.177 Cannot open the RSSI data file - RSSI.dat                                                                                                                                          
I: 2019-09-16 04:32:19.177 DMR Id Lookups                                                                                                                                                                     
I: 2019-09-16 04:32:19.177     File: /var/lib/mmdvm/DMRIds.dat                                                                                                                                                
I: 2019-09-16 04:32:19.177     Reload: 24 hours                                                                                                                                                               
I: 2019-09-16 04:32:19.997 Loaded 143685 Ids to the DMR callsign lookup table                                                                                                                                 
I: 2019-09-16 04:32:19.997 DMR RF Parameters                                                                                                                                                                  
I: 2019-09-16 04:32:19.997     Id: 310795501                                                                                                                                                                  
I: 2019-09-16 04:32:19.997     Color Code: 1                                                                                                                                                                  
I: 2019-09-16 04:32:19.997     Self Only: no                                                                                                                                                                  
I: 2019-09-16 04:32:19.997     Embedded LC Only: yes                                                                                                                                                          
I: 2019-09-16 04:32:19.997     Dump Talker Alias Data: no                                                                                                                                                     
I: 2019-09-16 04:32:19.997     Prefixes: 0                                                                                                                                                                    
I: 2019-09-16 04:32:19.997     Call Hang: 3s                                                                                                                                                                  
I: 2019-09-16 04:32:19.997     TX Hang: 3s                                                                                                                                                                    
I: 2019-09-16 04:32:19.997     Mode Hang: 10s                                                                                                                                                                 
M: 2019-09-16 04:32:19.997 DMR, Opening INI file: DVSwitch.ini                                                                                                                                                
I: 2019-09-16 04:32:19.997 Started the DMR Id lookup reload thread                                                                                                                                            
M: 2019-09-16 04:32:19.998 DMR, Setting [DMR] Address -> 127.0.0.1                                                                                                                                            
M: 2019-09-16 04:32:19.998 DMR, Setting [DMR] TXPort -> 31100                                                                                                                                                 
M: 2019-09-16 04:32:19.998 DMR, Setting [DMR] RXPort -> 31103                                                                                                                                                 
M: 2019-09-16 04:32:19.998 DMR, Setting [DMR] Slot -> 2                                                                                                                                                       
M: 2019-09-16 04:32:19.998 DMR, Transmitting on 127.0.0.1:31100 and listening on port 31103.  Result = 1                                                                                                      
M: 2019-09-16 04:32:19.998 MMDVM_Bridge-20180423 is running                                                                                                                                                   
M: 2019-09-16 04:32:30.182 DMR, Logged into the master successfully                                                                                                                                           
M: 2019-09-16 04:33:14.319 DMR Slot 2, received network voice header from KI6ZHD to TG WB5WXI                                                                                                                 
M: 2019-09-16 04:33:15.097 DMR Talker Alias (Data Format 1, Received 6/23 char): 'KI6ZHD'                                                                                                                     
M: 2019-09-16 04:33:15.814 DMR Talker Alias (Data Format 1, Received 13/23 char): 'KI6ZHD KI6ZHD'                                                                                                             
M: 2019-09-16 04:33:16.280 DMR Slot 2, received network end of voice transmission, 1.9 seconds, 0% packet loss, BER: 0.0%                                                                                     
M: 2019-09-16 04:33:46.247 DMR Slot 2, received network voice header from KI6ZHD to TG WB5WXI                                                                                                                 
M: 2019-09-16 04:33:46.958 DMR Talker Alias (Data Format 1, Received 6/23 char): 'KI6ZHD'                                                                                                                     
M: 2019-09-16 04:33:47.661 DMR Talker Alias (Data Format 1, Received 13/23 char): 'KI6ZHD KI6ZHD'                                                                                                             
M: 2019-09-16 04:33:48.154 DMR Slot 2, received network end of voice transmission, 1.9 seconds, 3% packet loss, BER: 0.0%                                                                                     
M: 2019-09-16 04:34:00.013 DMR, TX state = ON                                                                                                                                                                 
I: 2019-09-16 04:34:00.013 DMR, Begin TX: src=3107955 rpt=310795501 dst=31075 slot=2 cc=1 metadata=3107955                                                                                                    
M: 2019-09-16 04:34:07.206 DMR, TX state = OFF, DMR frame count was 123 frames                                                                                                                                
M: 2019-09-16 04:34:26.172 DMR, TX state = ON                                                                                                                                                                 
I: 2019-09-16 04:34:26.173 DMR, Begin TX: src=3107955 rpt=310795501 dst=31075 slot=2 cc=1 metadata=3107955
M: 2019-09-16 04:34:45.596 DMR, TX state = ON
I: 2019-09-16 04:34:45.596 DMR, Begin TX: src=3107955 rpt=310795501 dst=31075 slot=2 cc=1 metadata=3107955
M: 2019-09-16 04:34:48.125 DMR, TX state = OFF, DMR frame count was 45 frames



Steve N4IRS
 



On 9/16/2019 12:49 AM, David Ranch wrote:

Hello Steve,

BTW, you left out ASL in your diagram.

Ah.. ok.  Should it be the following?

   DMR HT --> Motorola DMR repeater --> Brandmeister TG --> DV-Switch MMDVM --> ASL --> DV-Switch Analog_Bridge --> URI-X --> analog speaker
It should be:
DMR HT --> Motorola DMR repeater --> Brandmeister TG --> MMDVM_Bridge  --> Analog_Bridge --> ASL --> URI-X --> analog speaker



Let's make sure you are are running what we think you are running. Do this:
delete the log file
date
/opt/Analog_Bridge/Analog_Bridge /opt/Analog_Bridge/Analog_Bridge.ini

Then post the output from date and AB.


Ok, as requested, I shut everything down, moved all three log files, started things up in non-daemon mode, then keyed up the DMR HT.  I then also keyed up on the analog side and I could hear the DMR repeater key up and give a hum sound (no microphone was connected.. I asserted the signal via the URI-X's "CTCSS/PL" line.  Regardless of either type of key up, it didn't create any log entries in the Analog_Bridge.log file

I don't know of any way that if MB is communicating with AB that you would not see traffic in the log. Since you hear DMR audio out of the speaker, it must be working. I can't troubleshoot the "flapping" without the AB log. 



[root@ww6bay-asl log]# date
Sun Sep 15 21:37:05 PDT 2019

[root@ww6bay-asl log]# mv asterisk/messages asterisk/messages.orig                                                                                                                             
'asterisk/messages' -> 'asterisk/messages.orig' 

[root@ww6bay-asl log]# mv MMDVM_Bridge-2019-09-15.log MMDVM_Bridge-2019-09-15.log.orig                                                                                                         
'MMDVM_Bridge-2019-09-15.log' -> 'MMDVM_Bridge-2019-09-15.log.orig'  

[root@ww6bay-asl log]# mv Analog_Bridge.log Analog_Bridge.log.orig
'Analog_Bridge.log' -> 'Analog_Bridge.log.orig'




[root@ww6bay-asl log]# ls -la *
-rw-r--r-- 1 root root   3658 Sep 15 21:32 Analog_Bridge.log  <------------------------------------------------
-rw-r--r-- 1 root root  14569 Sep 14 20:42 Analog_Bridge.log.1
-rw-r--r-- 1 root root   8209 Sep 14 22:18 Analog_Bridge.log.2.gz
-rw-r--r-- 1 root root      0 Sep 15 00:00 Analog_Bridge.log.orig
-rw-r--r-- 1 root root  50319 Sep  8 16:57 MMDVM_Bridge-2019-09-08.log
-rw-r--r-- 1 root root  46397 Sep  9 16:33 MMDVM_Bridge-2019-09-09.log
-rw-r--r-- 1 root root  40802 Sep 10 13:29 MMDVM_Bridge-2019-09-10.log
-rw-r--r-- 1 root root  60824 Sep 11 16:03 MMDVM_Bridge-2019-09-11.log
-rw-r--r-- 1 root root  70045 Sep 12 16:43 MMDVM_Bridge-2019-09-12.log
-rw-r--r-- 1 root root  89382 Sep 13 16:57 MMDVM_Bridge-2019-09-13.log
-rw-r--r-- 1 root root  99209 Sep 14 16:59 MMDVM_Bridge-2019-09-14.log
-rw-r--r-- 1 root root 443294 Sep 15 16:59 MMDVM_Bridge-2019-09-15.log.orig
-rw-r--r-- 1 root root  87698 Sep 15 21:37 MMDVM_Bridge-2019-09-16.log
-rw------- 1 root utmp      0 Feb 22  2016 btmp
-rw------- 1 root root  24024 Sep 15 15:15 faillog
-rw-r--r-- 1 root root 292292 Sep 15 15:15 lastlog
-rw-rw-r-- 1 root utmp   3072 Sep 15 15:15 wtmp

asterisk:
total 624
drwxr-xr-x 2 root root    140 Sep 15 21:32 .
drwxr-xr-x 4 root root    420 Sep 15 21:32 ..
-rw-r--r-- 1 root root 485619 Sep 15 03:37 astdb.txt
-rw-r--r-- 1 root root      0 Sep  7 17:52 event_log
-rw-r--r-- 1 root root  10011 Sep 15 21:37 messages
-rw-r--r-- 1 root root 133569 Sep 15 21:30 messages.orig
-rw-r--r-- 1 root root    152 Sep 15 21:32 queue_log

httpd:
total 8
drwxr-xr-x 2 root root 120 Sep  8 00:00 .
drwxr-xr-x 4 root root 420 Sep 15 21:32 ..
-rw-r--r-- 1 root root   0 Sep  8 00:00 access_log
-rw-r--r-- 1 root root   0 Sep  7 17:52 access_log.1
-rw-r--r-- 1 root root 372 Sep  8 00:00 error_log
-rw-r--r-- 1 root root 488 Sep  8 00:00 error_log.1


[root@ww6bay-asl log]# cat asterisk/messages                                                      
[Sep 15 21:32:11] NOTICE[6380] dnsmgr.c: Managed DNS entries will be refreshed every 300 seconds. 
[Sep 15 21:32:11] NOTICE[6380] cdr.c: CDR simple logging enabled.                                 
[Sep 15 21:32:11] NOTICE[6380] loader.c: 118 modules will be loaded.                              
[Sep 15 21:32:12] WARNING[6380] cdr_odbc.c: cdr_odbc: Unable to load config for ODBC CDR's: cdr_odbc.conf
[Sep 15 21:32:12] ERROR[6380] chan_voter.c: Unable to load config voter.conf                            
[Sep 15 21:32:12] NOTICE[6380] chan_irlp.c: Unable to load config irlp.conf                             
[Sep 15 21:32:12] ERROR[6380] codec_dahdi.c: Failed to open /dev/dahdi/transcode: No such file or directory
[Sep 15 21:32:12] NOTICE[6380] chan_usbradio.c: Unable to load config usbradio.conf                       
[Sep 15 21:32:12] WARNING[6380] pbx_spool.c: Unable to create queue directory /var/spool/asterisk/outgoing -- outgoing spool disabled
[Sep 15 21:32:12] ERROR[6380] chan_echolink.c: Unable to load config echolink.conf                                                  
[Sep 15 21:32:12] NOTICE[6380] pbx_ael.c: Starting AEL load process.                                                                
[Sep 15 21:32:12] NOTICE[6380] pbx_ael.c: AEL load process: calculated config file name '/usr/local/hamvoip-asterisk/etc/asterisk/extensions.ael'.
[Sep 15 21:32:12] NOTICE[6380] pbx_ael.c: File /usr/local/hamvoip-asterisk/etc/asterisk/extensions.ael not found; AEL declining load             
[Sep 15 21:32:12] NOTICE[6387] app_rpt.c: Normal Repeater Init  1998                                                                             
[Sep 15 21:32:12] NOTICE[6387] app_rpt.c: Normal Repeater Init  1999                                                                             
[Sep 15 21:32:12] NOTICE[6403] chan_simpleusb.c: Assigned USB device 1-1.4:1.0 to simpleusb channel usb                                          
[Sep 15 21:32:12] NOTICE[6403] chan_simpleusb.c: Loaded parameters from simpleusb_tune_usb.conf for device usb .                                 
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: cancel_pfxfone!![Sep 15 21:32:22] NOTICE[6402] app_rpt.c: cancel_pfxfone!![Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Starting node lookup: My node number: 1998, seeking node = 1                                                                                                                                                                                                   
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Not found in nodes stanza: node = 1                                                                                                                                 
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: cancel_pfxfone!![Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Starting node lookup: My node number: 1998, seeking node = 19                                             
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Not found in nodes stanza: node = 19                                                                                                                                
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: cancel_pfxfone!![Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Starting node lookup: My node number: 1998, seeking node = 199                                            
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Not found in nodes stanza: node = 199                                                                                                                               
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: cancel_pfxfone!![Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Starting node lookup: My node number: 1998, seeking node = 1999                                           
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Found node via nodes stanza: node = 1999, val = radio@....0.1/1999,NONE                                                                                           
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Connect attempt to node 1999                                                                                                                                        
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Mode: Transceive                                                                                                                                                    
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Connection type: Normal                                                                                                                                             
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: rpt (remote) initiating call to IAX2/radio@....0.1:4569/1999 on IAX2/127.0.0.1:4569-2968                                                                          
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=3                                                                                                                           
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=46                                                                                                                          
[Sep 15 21:32:22] NOTICE[6407] app_rpt.c: Starting node lookup: My node number: 1999, seeking node = 1998                                                                                                     
[Sep 15 21:32:22] NOTICE[6407] app_rpt.c: Found node via nodes stanza: node = 1998, val = radio@....0.1:4569/1998,NONE                                                                                      
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=9                                                                                                                           
[Sep 15 21:32:22] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=46                                                                                                                          
[Sep 15 21:32:22] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() entered mode=46                                                                                                                          
[Sep 15 21:32:22] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() entered mode=46                                                                                                                          
[Sep 15 21:32:42] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=0                                                                                                                           
[Sep 15 21:32:42] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() exit                                                                                                                                     
[Sep 15 21:32:42] NOTICE[6412] app_rpt.c: [1998] telemetry chan to teleconf                                                                                                                                   
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2                                                               
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx unkey                                                                                                                                                       
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2                                                       
[Sep 15 21:33:14] NOTICE[6402] app_rpt.c: Last = 1999, K                                                                                                                                                      
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5                                                           
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** cos state unkey                                                                                                                                                
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx un-key                                                                                                                                                      
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2                                                               
[Sep 15 21:33:14] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:14] NOTICE[6402] app_rpt.c: Last = 1999, K                                                                                                                                                      
[Sep 15 21:33:15] NOTICE[6402] app_rpt.c: TIMER is out of bounds! elap = 31                                                                                                                                   
[Sep 15 21:33:16] NOTICE[6404] app_rpt.c: **** rx unkey                                                                                                                                                       
[Sep 15 21:33:16] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2                                                       
[Sep 15 21:33:16] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5                                                           
[Sep 15 21:33:16] NOTICE[6404] app_rpt.c: **** cos state unkey                                                                                                                                                
[Sep 15 21:33:16] NOTICE[6404] app_rpt.c: **** rx un-key                                                                                                                                                      
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2                                                               
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx key                                                                                                                                                         
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx unkey                                                                                                                                                       
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 15 21:33:46] NOTICE[6402] app_rpt.c: Last = 1999, K
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** cos state unkey
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx un-key
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx key
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 15 21:33:46] NOTICE[6404] app_rpt.c: **** rx key
[Sep 15 21:33:46] NOTICE[6402] app_rpt.c: Last = 1999, K
[Sep 15 21:33:48] NOTICE[6404] app_rpt.c: **** rx unkey
[Sep 15 21:33:48] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 15 21:33:48] NOTICE[6404] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 15 21:33:48] NOTICE[6404] app_rpt.c: **** cos state unkey
[Sep 15 21:33:48] NOTICE[6404] app_rpt.c: **** rx un-key
[Sep 15 21:33:59] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:33:59] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 15 21:33:59] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:34:00] NOTICE[6404] app_rpt.c: Last = 1998, K
[Sep 15 21:34:00] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() entered mode=0
[Sep 15 21:34:00] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() exit
[Sep 15 21:34:00] NOTICE[6413] app_rpt.c: [1999] telemetry chan to teleconf
[Sep 15 21:34:07] NOTICE[6402] app_rpt.c: **** rx unkey
[Sep 15 21:34:07] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 15 21:34:07] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 15 21:34:07] NOTICE[6402] app_rpt.c: **** cos state unkey
[Sep 15 21:34:07] NOTICE[6402] app_rpt.c: **** rx un-key
[Sep 15 21:34:26] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:34:26] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 15 21:34:26] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:34:26] NOTICE[6404] app_rpt.c: Last = 1998, K
[Sep 15 21:34:31] NOTICE[6402] app_rpt.c: **** rx unkey
[Sep 15 21:34:31] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 15 21:34:31] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 15 21:34:31] NOTICE[6402] app_rpt.c: **** cos state unkey
[Sep 15 21:34:31] NOTICE[6402] app_rpt.c: **** rx un-key
[Sep 15 21:34:45] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:34:45] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_CLEAR, event=COS_ACTIVE, new state = COS_SM_ACTIVE, return_cos_value=2
[Sep 15 21:34:45] NOTICE[6402] app_rpt.c: **** rx key
[Sep 15 21:34:45] NOTICE[6404] app_rpt.c: Last = 1998, K
[Sep 15 21:34:48] NOTICE[6402] app_rpt.c: **** rx unkey
[Sep 15 21:34:48] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE, event=COS_INACTIVE, new state = COS_SM_ACTIVE_FLAP, return_cos_value=2
[Sep 15 21:34:48] NOTICE[6402] app_rpt.c: **** cos_state_machine: COS_SM_ACTIVE_FLAP, event=COS_TIMER, new state = COS_SM_CLEAR, return_cos_value=5
[Sep 15 21:34:48] NOTICE[6402] app_rpt.c: **** cos state unkey
[Sep 15 21:34:48] NOTICE[6402] app_rpt.c: **** rx un-key
[Sep 15 21:37:42] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() entered mode=0
[Sep 15 21:37:42] NOTICE[6402] app_rpt.c: Tracepoint rpt_telemetry() exit
[Sep 15 21:37:42] NOTICE[6420] app_rpt.c: [1998] telemetry chan to teleconf
[Sep 15 21:39:00] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() entered mode=0
[Sep 15 21:39:00] NOTICE[6404] app_rpt.c: Tracepoint rpt_telemetry() exit
[Sep 15 21:39:00] NOTICE[6423] app_rpt.c: [1999] telemetry chan to teleconf




[root@ww6bay-asl log]# cat Analog_Bridge.log   
I: 2019-09-16 04:32:27.676 Analog Bridge Version 1.1 Wed  9 May 05:56:17 EDT 2018
I: 2019-09-16 04:32:27.677 Copyright (C) 2018 DVSwitch, INAD.                   
I: 2019-09-16 04:32:27.677 Created by Mike N4IRR and Steve N4IRS                
I: 2019-09-16 04:32:27.677 Analog Bridge comes with ABSOLUTELY NO WARRANTY      
I: 2019-09-16 04:32:27.677                                                      
I: 2019-09-16 04:32:27.677 This software is for use on amateur radio networks only,
I: 2019-09-16 04:32:27.677 it is to be used for educational purposes only. Its use on
I: 2019-09-16 04:32:27.677 commercial networks is strictly prohibited.              
I: 2019-09-16 04:32:27.677
I: 2019-09-16 04:32:27.677 Analog Bridge is starting
M: 2019-09-16 04:32:27.677 Setting [GENERAL] logLevel -> 1   <-------------------------------------------------------
M: 2019-09-16 04:32:27.677 Setting [GENERAL] exportMetadata -> true
M: 2019-09-16 04:32:27.677 Setting [GENERAL] subscriberFile -> /var/lib/dvswitch/subscriber_ids.csv
M: 2019-09-16 04:32:27.678 Setting [GENERAL] decoderFallBack -> false
M: 2019-09-16 04:32:27.678 Setting [GENERAL] useEmulator -> false
M: 2019-09-16 04:32:27.678 Setting [GENERAL] emulatorAddress -> 127.0.0.1:2470
M: 2019-09-16 04:32:27.678 Setting [GENERAL] outputAudioDevice -> /dev/null
M: 2019-09-16 04:32:27.678 Setting [GENERAL] inputAudioDevice -> /dev/null
M: 2019-09-16 04:32:27.678 Setting [GENERAL] useMicrophone -> false
M: 2019-09-16 04:32:27.678 Setting [GENERAL] useVox -> false
M: 2019-09-16 04:32:27.678 Setting [GENERAL] voxDecay -> 2
M: 2019-09-16 04:32:27.678 Setting [GENERAL] voxTrigger -> 200
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] server -> 127.0.0.1
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] fromDMRPort -> 31100
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] toDMRPort -> 31103
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] ambeMode -> DMR
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] minTxTimeMS -> 2000
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] gatewayDmrId -> 3107955
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] repeaterID -> 310795501
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] txTg -> 31075
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] txTs -> 2
M: 2019-09-16 04:32:27.678 Setting [AMBE_AUDIO] colorCode -> 1
M: 2019-09-16 04:32:27.678 Setting [USRP] server -> 127.0.0.1
M: 2019-09-16 04:32:27.678 Setting [USRP] toASLPort -> 32001
M: 2019-09-16 04:32:27.678 Setting [USRP] fromASLPort -> 34001
M: 2019-09-16 04:32:27.678 Setting [USRP] aslAudio -> AUDIO_UNITY
M: 2019-09-16 04:32:27.679 Setting [USRP] agcGain -> -20
M: 2019-09-16 04:32:27.679 Setting [USRP] dmrAudio -> AUDIO_UNITY
M: 2019-09-16 04:32:27.679 Setting [USRP] dmrGain -> 0.35
M: 2019-09-16 04:32:27.679 Setting [DV3000] server -> /dev/ttyUSB0
M: 2019-09-16 04:32:27.679 Setting [DV3000] port -> 460800
M: 2019-09-16 04:32:27.679 Setting [DV3000] serial -> true
W: 2019-09-16 04:32:27.679 ioctl reset error
W: 2019-09-16 04:32:27.679 ioctl speed error
W: 2019-09-16 04:32:27.679 ioctl stereo error
W: 2019-09-16 04:32:27.679 ioctl setfmt error
M: 2019-09-16 04:32:27.679 Audio In/Out Device: /dev/null
I: 2019-09-16 04:32:27.679 Open UDP listener on 127.0.0.1:31100
I: 2019-09-16 04:32:27.679 Open USRP on 127.0.0.1:32001
D: 2019-09-16 04:32:27.679 Attempt to  init DV3000 in DMR mode
M: 2019-09-16 04:32:27.679 Connecting to DV3000 hardware......
M: 2019-09-16 04:32:27.691 Begin DV3000 decode
I: 2019-09-16 04:32:27.692 Subscriber IDs loaded: 0
I: 2019-09-16 04:32:27.692 Default extended metadata <3107955>
I: 2019-09-16 04:32:27.692 Using hardware AMBE vocoder
I: 2019-09-16 04:32:27.692 Starting Analog_Bridge --> USRP thread
I: 2019-09-16 04:32:27.692 Open UDP listener on 127.0.0.1:34001
I: 2019-09-16 04:32:27.692 Starting USRP --> HB_Bridge thread



[root@ww6bay-asl log]# cat MMDVM_Bridge-2019-09-16.log

I: 2019-09-16 04:32:19.066 MMDVM_Bridge:                                                                                                                                                                      
I: 2019-09-16 04:32:19.066 Portions Copyright (C) 2018 DVSwitch, INAD.                                                                                                                                        
I: 2019-09-16 04:32:19.066 Hacks by Mike N4IRR and Steve N4IRS                                                                                                                                                
I: 2019-09-16 04:32:19.066 =-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-                                                                                                                                       
I: 2019-09-16 04:32:19.066 This software is for use on amateur radio networks only,                                                                                                                           
I: 2019-09-16 04:32:19.066 it is to be used for educational purposes only. Its use on                                                                                                                         
I: 2019-09-16 04:32:19.066 commercial networks is strictly prohibited.                                                                                                                                        
I: 2019-09-16 04:32:19.066 Copyright(C) 2015-2018 by Jonathan Naylor, G4KLX and others                                                                                                                        
M: 2019-09-16 04:32:19.066 MMDVM_Bridge-20180423 is starting                                                                                                                                                  
M: 2019-09-16 04:32:19.066 Built 19:26:19 Jul 26 2018 (GitID #903ae69)                                                                                                                                        
I: 2019-09-16 04:32:19.066 General Parameters                                                                                                                                                                 
I: 2019-09-16 04:32:19.066     Callsign:                                                                                                                                                                      
I: 2019-09-16 04:32:19.066     Id: 310795501                                                                                                                                                                  
I: 2019-09-16 04:32:19.066     Duplex: no                                                                                                                                                                     
I: 2019-09-16 04:32:19.066     Timeout: 180s                                                                                                                                                                  
I: 2019-09-16 04:32:19.066     D-Star: disabled                                                                                                                                                               
I: 2019-09-16 04:32:19.067     DMR: enabled                                                                                                                                                                   
I: 2019-09-16 04:32:19.067     YSF: disabled                                                                                                                                                                  
I: 2019-09-16 04:32:19.067     P25: disabled                                                                                                                                                                  
I: 2019-09-16 04:32:19.067     NXDN: disabled                                                                                                                                                                 
I: 2019-09-16 04:32:19.067 Modem Parameters                                                                                                                                                                   
I: 2019-09-16 04:32:19.067     Port: /dev/null                                                                                                                                                                
I: 2019-09-16 04:32:19.067     RX Invert: no                                                                                                                                                                  
I: 2019-09-16 04:32:19.067     TX Invert: no                                                                                                                                                                  
I: 2019-09-16 04:32:19.067     PTT Invert: no                                                                                                                                                                 
I: 2019-09-16 04:32:19.067     TX Delay: 100ms                                                                                                                                                                
I: 2019-09-16 04:32:19.067     RX Offset: 0Hz                                                                                                                                                                 
I: 2019-09-16 04:32:19.067     TX Offset: 0Hz                                                                                                                                                                 
I: 2019-09-16 04:32:19.067     RX DC Offset: 0                                                                                                                                                                
I: 2019-09-16 04:32:19.067     TX DC Offset: 0                                                                                                                                                                
I: 2019-09-16 04:32:19.067     RF Level: 100.0%                                                                                                                                                               
I: 2019-09-16 04:32:19.067     DMR Delay: 0 (0.0ms)                                                                                                                                                           
I: 2019-09-16 04:32:19.067     RX Level: 50.0%                                                                                                                                                                
I: 2019-09-16 04:32:19.067     CW Id TX Level: 50.0%                                                                                                                                                          
I: 2019-09-16 04:32:19.067     D-Star TX Level: 50.0%                                                                                                                                                         
I: 2019-09-16 04:32:19.067     DMR TX Level: 50.0%                                                                                                                                                            
I: 2019-09-16 04:32:19.067     YSF TX Level: 50.0%                                                                                                                                                            
I: 2019-09-16 04:32:19.067     P25 TX Level: 50.0%                                                                                                                                                            
I: 2019-09-16 04:32:19.067     NXDN TX Level: 50.0%                                                                                                                                                           
I: 2019-09-16 04:32:19.067     RX Frequency: 222340000Hz (222340000Hz)                                                                                                                                        
I: 2019-09-16 04:32:19.067     TX Frequency: 224940000Hz (224940000Hz)                                                                                                                                        
M: 2019-09-16 04:32:19.068 Opening the MMDVM                                                                                                                                                                  
I: 2019-09-16 04:32:19.068 Display Parameters                                                                                                                                                                 
I: 2019-09-16 04:32:19.068     Type:                                                                                                                                                                          
I: 2019-09-16 04:32:19.068 DMR Network Parameters                                                                                                                                                             
I: 2019-09-16 04:32:19.068     Address: 3108.repeater.net                                                                                                                                                     
I: 2019-09-16 04:32:19.068     Port: 62031                                                                                                                                                                    
I: 2019-09-16 04:32:19.068     Local: 62032                                                                                                                                                                   
I: 2019-09-16 04:32:19.068     Jitter: 360ms                                                                                                                                                                  
I: 2019-09-16 04:32:19.068     Slot 1: disabled                                                                                                                                                               
I: 2019-09-16 04:32:19.068     Slot 2: enabled                                                                                                                                                                
I: 2019-09-16 04:32:19.068     Mode Hang: 3s                                                                                                                                                                  
I: 2019-09-16 04:32:19.176 Info Parameters                                                                                                                                                                    
I: 2019-09-16 04:32:19.176     Callsign:                                                                                                                                                                      
I: 2019-09-16 04:32:19.177     RX Frequency: 222340000Hz                                                                                                                                                      
I: 2019-09-16 04:32:19.177     TX Frequency: 224940000Hz                                                                                                                                                      
I: 2019-09-16 04:32:19.177     Power: 1W                                                                                                                                                                      
I: 2019-09-16 04:32:19.177     Latitude: 41.733299deg N                                                                                                                                                       
I: 2019-09-16 04:32:19.177     Longitude: -50.399899deg E                                                                                                                                                     
I: 2019-09-16 04:32:19.177     Height: 0m                                                                                                                                                                     
I: 2019-09-16 04:32:19.177     Location: "Iceberg, North Atlantic"                                                                                                                                            
I: 2019-09-16 04:32:19.177     Description: "123cba"                                                                                                                                                          
I: 2019-09-16 04:32:19.177     URL: "https://groups.io/g/DVSwitch"                                                                                                                                            
M: 2019-09-16 04:32:19.177 DMR, Opening DMR Network                                                                                                                                                           
I: 2019-09-16 04:32:19.177 RSSI                                                                                                                                                                               
I: 2019-09-16 04:32:19.177     Mapping File: RSSI.dat                                                                                                                                                         
W: 2019-09-16 04:32:19.177 Cannot open the RSSI data file - RSSI.dat                                                                                                                                          
I: 2019-09-16 04:32:19.177 DMR Id Lookups                                                                                                                                                                     
I: 2019-09-16 04:32:19.177     File: /var/lib/mmdvm/DMRIds.dat                                                                                                                                                
I: 2019-09-16 04:32:19.177     Reload: 24 hours                                                                                                                                                               
I: 2019-09-16 04:32:19.997 Loaded 143685 Ids to the DMR callsign lookup table                                                                                                                                 
I: 2019-09-16 04:32:19.997 DMR RF Parameters                                                                                                                                                                  
I: 2019-09-16 04:32:19.997     Id: 310795501                                                                                                                                                                  
I: 2019-09-16 04:32:19.997     Color Code: 1                                                                                                                                                                  
I: 2019-09-16 04:32:19.997     Self Only: no                                                                                                                                                                  
I: 2019-09-16 04:32:19.997     Embedded LC Only: yes                                                                                                                                                          
I: 2019-09-16 04:32:19.997     Dump Talker Alias Data: no                                                                                                                                                     
I: 2019-09-16 04:32:19.997     Prefixes: 0                                                                                                                                                                    
I: 2019-09-16 04:32:19.997     Call Hang: 3s                                                                                                                                                                  
I: 2019-09-16 04:32:19.997     TX Hang: 3s                                                                                                                                                                    
I: 2019-09-16 04:32:19.997     Mode Hang: 10s                                                                                                                                                                 
M: 2019-09-16 04:32:19.997 DMR, Opening INI file: DVSwitch.ini                                                                                                                                                
I: 2019-09-16 04:32:19.997 Started the DMR Id lookup reload thread                                                                                                                                            
M: 2019-09-16 04:32:19.998 DMR, Setting [DMR] Address -> 127.0.0.1                                                                                                                                            
M: 2019-09-16 04:32:19.998 DMR, Setting [DMR] TXPort -> 31100                           ��                                                                                                                     
M: 2019-09-16 04:32:19.998 DMR, Setting [DMR] RXPort -> 31103                                                                                                                                                 
M: 2019-09-16 04:32:19.998 DMR, Setting [DMR] Slot -> 2                                                                                                                                                       
M: 2019-09-16 04:32:19.998 DMR, Transmitting on 127.0.0.1:31100 and listening on port 31103.  Result = 1                                                                                                      
M: 2019-09-16 04:32:19.998 MMDVM_Bridge-20180423 is running                                                                                                                                                   
M: 2019-09-16 04:32:30.182 DMR, Logged into the master successfully                                                                                                                                           
M: 2019-09-16 04:33:14.319 DMR Slot 2, received network voice header from KI6ZHD to TG WB5WXI                                                                                                                 
M: 2019-09-16 04:33:15.097 DMR Talker Alias (Data Format 1, Received 6/23 char): 'KI6ZHD'                                                                                                                     
M: 2019-09-16 04:33:15.814 DMR Talker Alias (Data Format 1, Received 13/23 char): 'KI6ZHD KI6ZHD'                                                                                                             
M: 2019-09-16 04:33:16.280 DMR Slot 2, received network end of voice transmission, 1.9 seconds, 0% packet loss, BER: 0.0%                                                                                     
M: 2019-09-16 04:33:46.247 DMR Slot 2, received network voice header from KI6ZHD to TG WB5WXI                                                                                                                 
M: 2019-09-16 04:33:46.958 DMR Talker Alias (Data Format 1, Received 6/23 char): 'KI6ZHD'                                                                                                                     
M: 2019-09-16 04:33:47.661 DMR Talker Alias (Data Format 1, Received 13/23 char): 'KI6ZHD KI6ZHD'                                                                                                             
M: 2019-09-16 04:33:48.154 DMR Slot 2, received network end of voice transmission, 1.9 seconds, 3% packet loss, BER: 0.0%                                                                                     
M: 2019-09-16 04:34:00.013 DMR, TX state = ON                                                                                                                                                                 
I: 2019-09-16 04:34:00.013 DMR, Begin TX: src=3107955 rpt=310795501 dst=31075 slot=2 cc=1 metadata=3107955                                                                                                    
M: 2019-09-16 04:34:07.206 DMR, TX state = OFF, DMR frame count was 123 frames                                                                                                                                
M: 2019-09-16 04:34:26.172 DMR, TX state = ON                                                                                                                                                                 
I: 2019-09-16 04:34:26.173 DMR, Begin TX: src=3107955 rpt=310795501 dst=31075 slot=2 cc=1 metadata=3107955
M: 2019-09-16 04:34:45.596 DMR, TX state = ON
I: 2019-09-16 04:34:45.596 DMR, Begin TX: src=3107955 rpt=310795501 dst=31075 slot=2 cc=1 metadata=3107955
M: 2019-09-16 04:34:48.125 DMR, TX state = OFF, DMR frame count was 45 frames