OP25 Boatbod error

[WX4JCW]

what would be causing this issue, im trying to listen in non trunked mode using the following command line

./rx.py --crypt-behavior=0 --args "rtl" --gains 'lna:36' -S 960000 -f 851.8625000e6 -X -q 0 -D fsk4 -v 10 -2 -V -U 2> COIRS.2

now here is the error

Exception in thread Thread-1:
Traceback (most recent call last):
  File "/usr/lib/python3.8/threading.py", line 932, in _bootstrap_inner
    self.run()
  File "./rx.py", line 974, in run
10/23/21 19:37:20.001639 [0] NAC 0x170 TDU15:  LCW: ec=0, pb=0, sf=0, lco=0 : 00 00 04 00 27 12 00 00 00, gly_errs=0
    self.callback(msg)
  File "/home/jason/op25/op25/gr-op25_repeater/apps/trunking.py", line 1637, in process_qmsg
    updated += self.trunked_systems[nac].decode_fdma_lcw(s, curr_time)
KeyError: 368

this is a fresh install, the regular trunk command line works perfectly

this came up in the command window when i closed

./op25c.sh: line 1:  2789 Segmentation fault      (core dumped) ./rx.py --crypt-behavior=0 --args "rtl" --gains 'lna:36' -S 960000 -f 851.8625000e6 -X -q -1 -D fsk4 -v 10 -2 -V -U 2> COIRS.2

 

[WX4JCW]

also I am noticing no radio id's are showing with verbosity of 10 anymore

 

[WX4JCW]

Nope this is a clean install on Virtual box
It only errors out when trying to monitor conventional P25 if I monitor a trunk system all is well, but the radio ID issue is on both

 

[WX4JCW]

I didn’t update my PI version in a while and it works fine

 

[WX4JCW]

i removed -D fsk4 and the voice decodes now fine no errors in decode rate but the above exceptions still remain, and no srcaddr at all

here is what i am seeing with -v 10 setting

10/24/21 00:33:58.926027 [0] NAC 0x170 HDU:  ESS: tgid=10011, mfid=0, algid=80, keyid=0, mi=00 00 00 00 00 00 00 00 00, gly_errs=0, rs_errs=0
10/24/21 00:33:59.197485 [0] NAC 0x170 LDU1: LCW: ec=0, pb=0, sf=0, lco=0 : 00 00 04 00 27 1b 00 00 00
10/24/21 00:33:59.197539 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.197706 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.197746 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.197776 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.197821 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.197852 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.197880 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.197907 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.197934 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.334499 [0] NAC 0x170 LDU2: ESS: algid=80, keyid=0, mi=00 00 00 00 00 00 00 00 00, rs_errs=0
10/24/21 00:33:59.334567 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.334626 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.334658 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.334687 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.334715 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.334743 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.334770 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:33:59.334811 [0] IMBE 18 3e 92 32 1e 40 00 24 0b 00 52 errs 0
10/24/21 00:33:59.335152 [0] IMBE a8 56 40 9c ba 9a 00 0e 6f 3e a9 errs 0
10/24/21 00:33:59.471595 [0] NAC 0x170 LDU1: LCW: ec=0, pb=0, sf=1, lco=34 : 62 00 31 7c 01 02 02 cd 70
10/24/21 00:33:59.471650 [0] IMBE 18 d6 43 37 82 bb 06 f6 fc 3d 72 errs 0
10/24/21 00:33:59.472003 [0] IMBE 18 d7 55 89 ad e6 06 4e e8 aa 8b errs 0
10/24/21 00:33:59.472266 [0] IMBE 19 77 84 02 68 76 00 d1 cd ce 62 errs 0
10/24/21 00:33:59.472543 [0] IMBE 18 e7 21 55 28 de 07 8f e7 a3 93 errs 0
10/24/21 00:33:59.472802 [0] IMBE 4c 5d 6a 34 ce 4c ae 7b dd e4 96 errs 0
10/24/21 00:33:59.472943 [0] IMBE 98 03 96 1d f0 b6 00 0e 0b 9d 81 errs 0
10/24/21 00:33:59.473262 [0] IMBE 18 8e 73 c3 a4 3f 05 a6 14 7f 3a errs 0
10/24/21 00:33:59.473591 [0] IMBE 18 d1 cc 8e 2e e7 00 45 29 28 3b errs 0
10/24/21 00:33:59.473857 [0] IMBE 18 e2 aa 73 4f 16 01 34 36 b6 02 errs 0
10/24/21 00:33:59.743529 [0] NAC 0x170 LDU2: ESS: algid=80, keyid=0, mi=00 00 00 00 00 00 00 00 00, rs_errs=0
10/24/21 00:33:59.743576 [0] IMBE 44 7d a5 86 f9 fe c0 c6 66 04 71 errs 0
10/24/21 00:33:59.743817 [0] IMBE 44 f8 93 d9 41 1d fe fe a6 83 7a errs 0
10/24/21 00:33:59.743890 [0] IMBE 49 59 86 5b d2 9e ee 67 98 0a f1 errs 0
10/24/21 00:33:59.744035 [0] IMBE 49 59 96 1b b5 98 ee 5d ff 17 94 errs 0
10/24/21 00:33:59.744224 [0] IMBE 49 4e 68 e7 1f 68 fe 0b 32 df b7 errs 0
10/24/21 00:33:59.744317 [0] IMBE 18 29 bc 7e da e0 07 e2 14 b9 f2 errs 0
10/24/21 00:33:59.744595 [0] IMBE 18 63 29 79 41 23 05 f9 85 53 33 errs 0
10/24/21 00:33:59.744839 [0] IMBE 18 2a ba da 6f 46 03 27 5f b1 0a errs 0
10/24/21 00:33:59.745164 [0] IMBE 18 63 29 f9 5c 68 03 48 81 84 63 errs 0
10/24/21 00:33:59.880489 [0] NAC 0x170 LDU1: LCW: ec=0, pb=0, sf=0, lco=0 : 00 00 04 00 27 1b 20 38 ba
10/24/21 00:33:59.880523 [0] IMBE 18 63 29 49 ea 4e 03 d9 25 59 22 errs 0
10/24/21 00:33:59.880862 [0] IMBE 84 60 be 92 f0 1d 00 0b fe 2b dd errs 0
10/24/21 00:33:59.881159 [0] IMBE 88 4f 2c 15 59 10 00 0a c9 66 24 errs 1
10/24/21 00:33:59.881432 [0] IMBE 18 41 6d fd 09 8a 04 a7 af 09 73 errs 0
10/24/21 00:33:59.881722 [0] IMBE 18 63 29 09 7e 3c 02 3c cb 1c ca errs 0
10/24/21 00:33:59.881996 [0] IMBE 18 53 c8 98 ec de 02 73 79 e1 fb errs 0
10/24/21 00:33:59.882240 [0] IMBE 18 41 6d ed 88 8a 03 8d a6 94 1a errs 0
10/24/21 00:33:59.882488 [0] IMBE 18 53 c8 98 af a6 02 00 ab 73 e3 errs 0
10/24/21 00:33:59.882794 [0] IMBE 18 43 69 a1 4b 57 00 f7 ac 81 2a errs 0
10/24/21 00:34:00.018087 [0] NAC 0x170 LDU2: ESS: algid=80, keyid=0, mi=00 00 00 00 00 00 00 00 00, rs_errs=0
10/24/21 00:34:00.018131 [0] IMBE 18 73 09 09 ab 16 03 b2 e7 31 f3 errs 0
10/24/21 00:34:00.018496 [0] IMBE 18 53 49 89 fb 24 01 f2 05 fb 22 errs 0
10/24/21 00:34:00.018751 [0] IMBE 18 43 e8 a8 ce 02 01 25 f6 8a 13 errs 0
10/24/21 00:34:00.019003 [0] IMBE 18 52 4b ab cb 62 00 f5 ad 2b 02 errs 0
10/24/21 00:34:00.019388 [0] IMBE 18 61 2d dd bc 24 01 12 a2 0e db errs 0
10/24/21 00:34:00.019661 [0] IMBE 18 43 69 e9 ca a2 00 bf 6e e4 7a errs 1
10/24/21 00:34:00.019914 [0] IMBE 50 7b 87 b1 20 f8 c0 58 e7 10 ed errs 0
10/24/21 00:34:00.020136 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/24/21 00:34:00.020165 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 45 errs 0

 

[WX4JCW]

and here is my header with the error

Using Python /usr/bin/python3
gr-osmosdr 0.2.0.0 (0.2.0) gnuradio 3.8.1.0
built-in source types: file osmosdr fcd rtl rtl_tcp uhd miri hackrf bladerf rfspace airspy airspyhf soapy redpitaya freesrp
Using device #0 Realtek RTL2838UHIDIR SN: 00000001
Found Rafael Micro R820T tuner
[R82XX] PLL not locked!
gain: name: LNA range: start 0 stop 0 step 0
setting gain lna to 39
supported sample rates 250000-2560000 step 24000
[R82XX] PLL not locked!
Using two-stage decimator for speed=960000, decim=10/4 if1=96000 if2=24000
op25_audio::open_socket(): enabled udp host(127.0.0.1), wireshark(23456), audio(23456)
p25_frame_assembler_impl: do_imbe[1], do_output[0], do_audio_output[1], do_phase2_tdma[0], do_nocrypt[0]
metadata update not enabled
using ALSA sound system
audio device: default
Listening on 127.0.0.1:23456
python version detected: 3.8.10 (default, Sep 28 2021, 16:10:42)
[GCC 9.3.0]
Allocating 15 zero-copy buffers
10/24/21 00:33:43.023300 [0] NAC 0x170 TDU15:  10/24/21 00:33:43.023506 nac 170 type 15 state 1 len 0
10/24/21 00:33:43.023630 nac 170 type 19 state 1 len 9
Exception in thread Thread-1:
Traceback (most recent call last):
  File "/usr/lib/python3.8/threading.py", line 932, in _bootstrap_inner
LCW: ec=0, pb=0, sf=1, lco=34 : 62 00 31 7c 01 02 02 cd 70, gly_errs=0
    self.run()
  File "./rx.py", line 974, in run
    self.callback(msg)
  File "/home/jason/op25/op25/gr-op25_repeater/apps/trunking.py", line 1637, in process_qmsg
    updated += self.trunked_systems[nac].decode_fdma_lcw(s, curr_time)
KeyError: 368

 

[WX4JCW]

and for the record its this system COIRS (Central Ohio Interoperable Radio System) Trunking System, Delaware, Ohio - Scanner Frequencies

 

[wgbecks]

Exception in thread Thread-1:
Traceback (most recent call last):
  File "/usr/lib/python3.8/threading.py", line 932, in _bootstrap_inner
    self.run()
  File "./rx.py", line 974, in run
10/23/21 19:37:20.001639 [0] NAC 0x170 TDU15:  LCW: ec=0, pb=0, sf=0, lco=0 : 00 00 04 00 27 12 00 00 00, gly_errs=0
    self.callback(msg)
  File "/home/jason/op25/op25/gr-op25_repeater/apps/trunking.py", line 1637, in process_qmsg
    updated += self.trunked_systems[nac].decode_fdma_lcw(s, curr_time)
KeyError: 368

[/CODE]

I normally use multi_rx.py for use as a conventional receiver but have used rx.py in the past and thought that I would check it to see if something changed after reading your post.

What appears to be an error is possibly a result of using rx.py to decode conventional p25 whereby there is no trunk.tsv to parameterize trunking.py causing what seem like a fatal error message. However, at least in my case, I basically duplicated your rx.py command line and was able to decode a p25 test transmission with excellent audio while obtaining very similar data in my logfile.

See the text below in bold that was pulled from my logfile.

Bill


stderr.2

Using Python /usr/bin/python2
gr-osmosdr 0.1.4 (0.1.4) gnuradio 3.7.13.4
built-in source types: file osmosdr fcd rtl rtl_tcp uhd miri hackrf bladerf rfspace airspy airspyhf soapy redpitaya freesrp
Using AirSpy MINI v1.0.0-rc10-6-g4008185 2020-05-08, samplerates: 3M 6M
gain: name: LNA range: start 0 stop 15 step 1
gain: name: MIX range: start 0 stop 15 step 1
gain: name: IF range: start 0 stop 15 step 1
setting gain LNA to 5
setting gain MIX to 5
setting gain IF to 8
supported sample rates 3000000-6000000 step 3000000
Airspy decim:1 kernel size:47
Using two-stage decimator for speed=3000000, decim=30/4 if1=100000 if2=25000
op25_audio:pen_socket(): enabled udp host(127.0.0.1), wireshark(23456), audio(23456)
p25_frame_assembler_impl: do_imbe[1], do_output[0], do_audio_output[1], do_phase2_tdma[0], do_nocrypt[1]
metadata update not enabled
python version detected: 2.7.16 (default, Oct 10 2019, 22:02:15)
[GCC 8.3.0]
10/23/21 23:39:20.125734 [0] p25p1_fdma::check_timeout: expired
10/23/21 23:39:20.692304 [0] NAC 0x293 LDU1: LCW: ec=0, pb=0, sf=0, lco=0 : 00 00 04 00 00 01 00 1f 98
10/23/21 23:39:20.692448 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/23/21 23:39:20.692779 [0] IMBE 04 0c fd 7b fb 7d f2 7b 3d 9e 44 errs 0
10/23/21 23:39:20.692960 [0] IMBE 18 3e 92 32 1e 40 00 24 0b 00 52 errs 0
10/23/21 23:39:20.694409 [0] IMBE 18 2a ba 5a 33 67 00 84 d7 8a 63 errs 0
10/23/21 23:39:20.695802 [0] IMBE 8c 28 80 30 14 18 00 0b fc 77 e6 errs 0
Exception in thread Thread-1:
Traceback (most recent call last):
File "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
self.run()
File "./rx.py", line 974, in run
self.callback(msg)
File "/home/pi/op25/op25/gr-op25_repeater/apps/trunking.py", line 1637, in process_qmsg
updated += self.trunked_systems[nac].decode_fdma_lcw(s, curr_time)
KeyError: 659

 

[WX4JCW]

yes exactly, what I am getting at is there was a recent pull that this happened, I have OP25 on a PI and haven't updated in Months and it produces no errors at all, in fact the last GIT pull on my Virtual box hosed my install and I had to create a new one, normally you would put in -D fsk4 for a conventional channel but the decode was full of errors, and the missing srcaddr lines in the log are an issue

 

[boatbod]

The first traceback appears to be an error-checking failure on my part. I'll dig deeper as time permits.
The core-dump on close is unfortunate but not entirely unusual; it's something related to the way I'm deconstructing the gnuradio flowgraph.

NOTE: for ease of maintenance, nearly all user-related P25 protocol decode has been moved out of the c/c++ library up to the python trunking module. Since python crashed here (i.e. the traceback) it's not going to log anything even though the audio probably continues to play normally.

 

[WX4JCW]

The first traceback appears to be an error-checking failure on my part. I'll dig deeper as time permits.
The core-dump on close is unfortunate but not entirely unusual; it's something related to the way I'm deconstructing the gnuradio flowgraph.

NOTE: for ease of maintenance, nearly all user-related P25 protocol decode has been moved out of the c/c++ library up to the python trunking module. Since python crashed here (i.e. the traceback) it's not going to log anything even though the audio probably continues to play normally.

Any idea why the srcaddress is not showing anymore?

 

[boatbod]

Any idea why the srcaddress is not showing anymore?

Yes - see my earlier note regarding moving protocol decode out of the c/c++ library

NOTE: for ease of maintenance, nearly all user-related P25 protocol decode has been moved out of the c/c++ library up to the python trunking module. Since python crashed here (i.e. the traceback) it's not going to log anything even though the audio probably continues to play normally

 

[WX4JCW]

thank you sir, ill hold off updating my PI for a bit then, now is the Python crash on my end?

 

[boatbod]

thank you sir, ill hold off updating my PI for a bit then, now is the Python crash on my end?

The python crash is reproducible in my system. Unfortunately it's not as simple as adding additional validation because while that will fix the crash it won't resolve an underlying problem with P25 Conventional not showing the grp/src addresses in a debug log.

 

[WX4JCW]

Understood

 

[WX4JCW]

Understood I’ll just hold off

 

[boatbod]

I've pushed a fix for P25 Conventional using rx.py. Basically the change enables the python trunking module to start (using whatever nac it encounters on air) without a trunk.tsv file, with the caveat that it won't attempt to trunk-track and just provides protocol decode/logging support.

 

[wgbecks]

Nice work! I just confirmed the the fix by sending a P25 Conventional test signal from my service monitor into op25 with Group Address = 1
and Source Address = 8088.

10/25/21 21:14:35.085251 [0] lcw(00) grp_v_ch_usr: ga: 1 sa: 8088

 

[WX4JCW]

Thanks Boatbod, working now

 

[kb6mcc]

I having the very same issues and any helps are appreciated. OP25 works really really well begin with but it stop for whatever reasons? It stop work with Exception in thread Thread-1: from matter of an hours or 5+ hours.



OP25 is install on a fresh install Ubuntu
Description: Ubuntu 18.04.6 LTS
Release: 18.04

My startup
./rx.py --args 'hackrf' -N 'RF:14,IF:40,BB:52' -S 8000000 -q 0.6 -o 380e3 -O plughw:CARD=PCH,DEV=0 -2 -T city-dd.tsv -V -U -v 1 2> stderr.txt


Here is the error log.

Using Python /usr/bin/python2
gr-osmosdr 0.1.4 (0.1.4) gnuradio 3.7.11
built-in source types: file osmosdr fcd rtl rtl_tcp uhd miri hackrf bladerf rfspace airspy airspyhf soapy redpitaya freesrp
Using HackRF One with firmware local-57ed3eb
gain: name: RF range: start 0 stop 14 step 14
gain: name: IF range: start 0 stop 40 step 8
gain: name: BB range: start 0 stop 62 step 2
setting gain RF to 14
setting gain IF to 40
setting gain BB to 52
supported sample rates 8000000-20000000 step 2000000
Using two-stage decimator for speed=8000000, decim=80/4 if1=100000 if2=25000
op25_audio:pen_socket(): enabled udp host(127.0.0.1), wireshark(23456), audio(23456)
p25_frame_assembler_impl: do_imbe[1], do_output[0], do_audio_output[1], do_phase2_tdma[1], do_nocrypt[1]
11/30/21 21:04:03.491179 Reading blacklist file
11/30/21 21:04:03.491231 added talkgroup 6 from city-dd-bl.tsv
11/30/21 21:04:03.491252 added talkgroup 13 from city-dd-bl.tsv
11/30/21 21:04:03.491267 added talkgroup 16 from city-dd-bl.tsv
11/30/21 21:04:03.491279 added talkgroup 26 from city-dd-bl.tsv
11/30/21 21:04:03.491292 added talkgroup 30 from city-dd-bl.tsv
11/30/21 21:04:03.491303 added talkgroup 39 from city-dd-bl.tsv
11/30/21 21:04:03.491315 added talkgroup 41 from city-dd-bl.tsv
11/30/21 21:04:03.491326 added talkgroup 42 from city-dd-bl.tsv
11/30/21 21:04:03.491338 added talkgroup 47 from city-dd-bl.tsv
11/30/21 21:04:03.491349 added talkgroup 125 from city-dd-bl.tsv
11/30/21 21:04:03.491361 added talkgroup 2021 from city-dd-bl.tsv
11/30/21 21:04:03.491372 added talkgroup 2022 from city-dd-bl.tsv
11/30/21 21:04:03.491384 added talkgroup 20504 from city-dd-bl.tsv
11/30/21 21:04:03.491395 added talkgroup 20506 from city-dd-bl.tsv
metadata update not enabled
using ALSA sound system
audio device: plughw:CARD=PCH,DEV=0
Listening on 127.0.0.1:23456
python version detected: 2.7.17 (default, Feb 27 2021, 15:10:58)
[GCC 7.5.0]
11/30/21 21:04:03.777552 voice update: tg(65348), freq(852162500), slot(-), prio(3)
11/30/21 21:04:04.861015 voice update: tg(65348), freq(851337500), slot(-), prio(3)
11/30/21 21:04:06.924856 voice update: tg(65348), freq(851362500), slot(-), prio(3)
11/30/21 21:04:07.380585 voice update: tg(65348), freq(851362500), slot(-), prio(3)
.
.
.
12/01/21 02:32:43.555207 voice update: tg(20168), freq(852337500), slot(-), prio(3)
12/01/21 02:32:44.045320 voice update: tg(20168), freq(852337500), slot(-), prio(3)
12/01/21 02:32:46.862325 voice update: tg(20168), freq(852362500), slot(-), prio(3)
Exception in thread Thread-1:
Traceback (most recent call last):
File "/usr/lib/python2.7/threading.py", line 801, in __bootstrap_inner
self.run()
File "./rx.py", line 975, in run
self.callback(msg)
File "/home/ken/op25/op25/gr-op25_repeater/apps/trunking.py", line 1630, in process_qmsg
updated += self.trunked_systems[nac].decode_tsbk(t)
File "/home/ken/op25/op25/gr-op25_repeater/apps/trunking.py", line 689, in decode_tsbk
self.del_patch(sg, [ga])
UnboundLocalError: local variable 'ga' referenced before assignment