Livestream Failures #917

jmtuts · 2023-05-19T20:09:36Z

jmtuts
May 19, 2023

Starting today I am receiving livestream failures, and have zero detections, any ideas as to why this is happening or how to correct"?

Output from journalctl -f

lloydbayley · 2023-05-20T10:41:55Z

lloydbayley
May 20, 2023

Quick question. Are you completely up-to-date?

6 replies

lloydbayley May 20, 2023

Excellent. That's a jolly-good start. It appears as though something is preventing the streaming server from starting on port 8000.
Right before the error in your picture, there was a stop/start. Was this you restarting the service or was it the normal flow when you restart all services or restart the pi?

jmtuts May 20, 2023
Author

The output from journalctl -f was just part of the normal flow, I just monitor that as opposed to the "view Log" from the UI because it seems filtered.

lloydbayley May 20, 2023

That's fine, journalctl -f is a good idea for bug/weirdness reporting. I'm struggling to think of anything obvious here. Is this a standard install on a dedicated system? Could something else be using port 8000? Also, are you using a USB mic or an RTSP source?

lloydbayley May 20, 2023

I also think this might have more to do with ffmpeg than icecast. Will give it some thought.

jmtuts Aug 9, 2023
Author

It has started again, I tried starting the livestream through the BirdNet Pi app, here is the result:

● icecast2.service - LSB: Icecast2 streaming media server
Loaded: loaded (/etc/init.d/icecast2; generated)
Active: active (exited) since Wed 2023-08-09 11:31:28 MST; 9min ago
Docs: man:systemd-sysv-generator(8)
Tasks: 0 (limit: 1991)
Memory: 0B
CPU: 0
CGroup: /system.slice/icecast2.service

Aug 09 11:31:27 birdnet systemd[1]: Starting LSB: Icecast2 streaming media server...
Aug 09 11:31:28 birdnet icecast2[859]: Starting streaming media server: icecast2
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] WARN CONFIG/_parse_root Warning, not configured, using default value "localhost". This will cause problems, e.g. with YP directory listings.
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] WARN CONFIG/_parse_root Warning, not configured, using default value "Earth".
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] WARN CONFIG/_parse_root Warning, contact not configured, using default value "icemaster@localhost".
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] EROR connection/connection_setup_sockets Could not create listener socket on port 8000 bind 127.0.0.1
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] EROR connection/connection_setup_sockets Could not create listener socket on port 8001 bind 127.0.0.1
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] EROR connection/connection_setup_sockets No listening sockets established
Aug 09 11:31:28 birdnet icecast2[859]: .
Aug 09 11:31:28 birdnet systemd[1]: Started LSB: Icecast2 streaming media server.

● icecast2.service - LSB: Icecast2 streaming media server
Loaded: loaded (/etc/init.d/icecast2; generated)
Active: active (exited) since Wed 2023-08-09 11:31:28 MST; 10min ago
Docs: man:systemd-sysv-generator(8)
Tasks: 0 (limit: 1991)
Memory: 0B
CPU: 0
CGroup: /system.slice/icecast2.service

Aug 09 11:31:27 birdnet systemd[1]: Starting LSB: Icecast2 streaming media server...
Aug 09 11:31:28 birdnet icecast2[859]: Starting streaming media server: icecast2
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] WARN CONFIG/_parse_root Warning, not configured, using default value "localhost". This will cause problems, e.g. with YP directory listings.
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] WARN CONFIG/_parse_root Warning, not configured, using default value "Earth".
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] WARN CONFIG/_parse_root Warning, contact not configured, using default value "icemaster@localhost".
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] EROR connection/connection_setup_sockets Could not create listener socket on port 8000 bind 127.0.0.1
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] EROR connection/connection_setup_sockets Could not create listener socket on port 8001 bind 127.0.0.1
Aug 09 11:31:28 birdnet icecast2[903]: [2023-08-09 11:31:28] EROR connection/connection_setup_sockets No listening sockets established
Aug 09 11:31:28 birdnet icecast2[859]: .
Aug 09 11:31:28 birdnet systemd[1]: Started LSB: Icecast2 streaming media server.

● livestream.service - BirdNET-Pi Live Stream
Loaded: loaded (/home/pi/BirdNET-Pi/templates/livestream.service; enabled; vendor preset: enabled)
Active: activating (auto-restart) (Result: exit-code) since Wed 2023-08-09 11:41:29 MST; 10ms ago
Process: 9391 ExecStart=/usr/local/bin/livestream.sh (code=exited, status=1/FAILURE)
Main PID: 9391 (code=exited, status=1/FAILURE)
CPU: 936ms

jmtuts · 2023-05-20T18:40:40Z

jmtuts
May 20, 2023
Author

It is a stand alone build on the Libre Le Potato, i keep it updated as much as possible. My install is also public, although I can't get to this URL from inside of my own network FallenBirdNet.sytes.net Hope this helps, and I have no idea what ffmpeg or icecast are, so I can provide no help there. John

…

On Sat, May 20, 2023 at 8:13 AM lloydbayley ***@***.***> wrote: That's fine, journalctl -f is a good idea for bug/weirdness reporting. I'm struggling to think of anything obvious here. Is this a standard install on a dedicated system? Could something else be using port 8000? — Reply to this email directly, view it on GitHub <#917 (reply in thread)>, or unsubscribe <https://github.com/notifications/unsubscribe-auth/A5PPZE3OWXXJ3GM3O64OMHLXHDNTHANCNFSM6AAAAAAYIGEWBY> . You are receiving this because you authored the thread.Message ID: ***@***.***>

0 replies

ehpersonal38 · 2023-05-21T21:08:47Z

ehpersonal38
May 21, 2023
Collaborator

Please run

bash ~/BirdNET-Pi/scripts_print_diagnostic_info.sh

and paste the full output here so we can have more information to debug.

3 replies

jmtuts May 21, 2023
Author

pi@birdnet:~/BirdNET-Pi/scripts$ bash print_diagnostic_info.sh
========== caddy status ==========
● caddy.service - Caddy
Loaded: loaded (/lib/systemd/system/caddy.service; enabled; vendor preset: enabled)
Active: active (running) since Sun 2023-05-21 01:00:30 MST; 14h ago
Docs: https://caddyserver.com/docs/
Main PID: 859 (caddy)
Tasks: 11 (limit: 1991)
Memory: 53.3M
CPU: 1min 30.759s
CGroup: /system.slice/caddy.service
└─859 /usr/bin/caddy run --environ --config /etc/caddy/Caddyfile

May 21 01:00:30 birdnet caddy[859]: {"level":"info","ts":1684656030.7074852,"msg":"using provided configuration","config_file":"/etc/caddy/Caddyfile","config_adapter":""}
May 21 01:00:30 birdnet caddy[859]: {"level":"info","ts":1684656030.786268,"logger":"admin","msg":"admin endpoint started","address":"localhost:2019","enforce_origin":false,"origins":["//[::1]:2019","//127.0.0.1:2019","//localhost:2019"]}
May 21 01:00:30 birdnet caddy[859]: {"level":"info","ts":1684656030.8042724,"logger":"tls.cache.maintenance","msg":"started background certificate maintenance","cache":"0x400016af50"}
May 21 01:00:30 birdnet caddy[859]: {"level":"warn","ts":1684656030.8055296,"logger":"http","msg":"server is listening only on the HTTP port, so no automatic HTTPS will be applied to this server","server_name":"srv0","http_port":80}
May 21 01:00:30 birdnet caddy[859]: {"level":"info","ts":1684656030.8179693,"logger":"tls","msg":"cleaning storage unit","description":"FileStorage:/var/lib/caddy/.local/share/caddy"}
May 21 01:00:30 birdnet caddy[859]: {"level":"info","ts":1684656030.829094,"logger":"tls","msg":"finished cleaning storage units"}
May 21 01:00:30 birdnet caddy[859]: {"level":"info","ts":1684656030.8286374,"logger":"http.log","msg":"server running","name":"srv0","protocols":["h1","h2","h3"]}
May 21 01:00:30 birdnet caddy[859]: {"level":"info","ts":1684656030.8325737,"msg":"autosaved config (load with --resume flag)","file":"/var/lib/caddy/.config/caddy/autosave.json"}
May 21 01:00:30 birdnet systemd[1]: Started Caddy.
May 21 01:00:30 birdnet caddy[859]: {"level":"info","ts":1684656030.8371186,"msg":"serving initial configuration"}

========== birdnet_analysis status ==========
● birdnet_analysis.service - BirdNET Analysis
Loaded: loaded (/home/pi/BirdNET-Pi/templates/birdnet_analysis.service; enabled; vendor preset: enabled)
Active: active (running) since Sun 2023-05-21 15:13:25 MST; 8s ago
Main PID: 726738 (bash)
Tasks: 2 (limit: 1991)
Memory: 856.0K
CPU: 646ms
CGroup: /system.slice/birdnet_analysis.service
├─726738 bash /usr/local/bin/birdnet_analysis.sh
└─726896 sleep 2

May 21 15:13:25 birdnet systemd[1]: Started BirdNET Analysis.
May 21 15:13:26 birdnet birdnet_analysis.sh[726738]: Files loaded
May 21 15:13:26 birdnet birdnet_analysis.sh[726738]: RECORDING_LENGTH set to 25

========== birdnet_log status ==========
● birdnet_log.service - BirdNET Analysis Log
Loaded: loaded (/home/pi/BirdNET-Pi/templates/birdnet_log.service; enabled; vendor preset: enabled)
Active: active (running) since Sun 2023-05-21 01:00:18 MST; 14h ago
Main PID: 506 (gotty)
Tasks: 7 (limit: 1991)
Memory: 2.1M
CPU: 109ms
CGroup: /system.slice/birdnet_log.service
└─506 /usr/local/bin/gotty --address localhost -p 8080 -P log --title-format BirdNET-Pi Log birdnet_log.sh

May 21 01:00:18 birdnet systemd[1]: Started BirdNET Analysis Log.
May 21 01:00:20 birdnet gotty[506]: 2023/05/21 01:00:19 Loading config file at: /home/pi/.gotty
May 21 01:00:20 birdnet gotty[506]: 2023/05/21 01:00:19 GoTTY is starting with command: birdnet_log.sh
May 21 01:00:20 birdnet gotty[506]: 2023/05/21 01:00:19 HTTP server is listening at: http://127.0.0.1:8080/log/

========== birdnet_recording status ==========
● birdnet_recording.service - BirdNET Recording
Loaded: loaded (/home/pi/BirdNET-Pi/templates/birdnet_recording.service; enabled; vendor preset: enabled)
Active: active (running) since Sun 2023-05-21 14:53:17 MST; 20min ago
Main PID: 708648 (bash)
Tasks: 3 (limit: 1991)
Memory: 53.4M
CPU: 22.904s
CGroup: /system.slice/birdnet_recording.service
├─708648 bash /usr/local/bin/birdnet_recording.sh
└─708676 arecord -f S16_LE -c2 -r48000 -t wav --max-file-time 25 -D default --use-strftime /home/pi/BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav

May 21 14:53:17 birdnet systemd[1]: Started BirdNET Recording.
May 21 14:53:17 birdnet birdnet_recording.sh[708674]: tcp 0 0 127.0.0.1:5050 0.0.0.0:* LISTEN 509/python3
May 21 14:53:17 birdnet birdnet_recording.sh[708676]: Recording WAVE '/home/pi/BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav' : Signed 16 bit Little Endian, Rate 48000 Hz, Stereo

========== birdnet_server status ==========
● birdnet_server.service - BirdNET Analysis Server
Loaded: loaded (/home/pi/BirdNET-Pi/templates/birdnet_server.service; enabled; vendor preset: enabled)
Active: active (running) since Sun 2023-05-21 01:00:18 MST; 14h ago
Main PID: 509 (python3)
Tasks: 3 (limit: 1991)
Memory: 288.3M
CPU: 2h 50min 59.619s
CGroup: /system.slice/birdnet_server.service
└─509 /home/pi/BirdNET-Pi/birdnet/bin/python3 /usr/local/bin/server.py

May 21 15:12:30 birdnet python3[509]: READING AUDIO DATA... DONE! READ 8 CHUNKS.
May 21 15:12:30 birdnet python3[509]: Date-time: 2023-05-21 15:12:03
May 21 15:12:54 birdnet python3[509]: ANALYZING AUDIO... DONE! Time 3.8 SECONDS
May 21 15:12:54 birdnet python3[509]: WRITING RESULTS TO /home/pi/BirdSongs/May-2023/21-Sunday/2023-05-21-birdnet-15:12:03.wav.csv ... DONE! WROTE 0 RESULTS.
May 21 15:12:54 birdnet python3[509]: READING AUDIO DATA... DONE! READ 8 CHUNKS.
May 21 15:12:54 birdnet python3[509]: Date-time: 2023-05-21 15:12:28
May 21 15:13:19 birdnet python3[509]: ANALYZING AUDIO... DONE! Time 3.2 SECONDS
May 21 15:13:19 birdnet python3[509]: WRITING RESULTS TO /home/pi/BirdSongs/May-2023/21-Sunday/2023-05-21-birdnet-15:12:28.wav.csv ... DONE! WROTE 0 RESULTS.
May 21 15:13:19 birdnet python3[509]: READING AUDIO DATA... DONE! READ 8 CHUNKS.
May 21 15:13:19 birdnet python3[509]: Date-time: 2023-05-21 15:12:53

========== birdnet_stats status ==========
● birdnet_stats.service - BirdNET Stats
Loaded: loaded (/home/pi/BirdNET-Pi/templates/birdnet_stats.service; enabled; vendor preset: enabled)
Active: active (running) since Sun 2023-05-21 01:00:18 MST; 14h ago
Main PID: 511 (streamlit)
Tasks: 8 (limit: 1991)
Memory: 244.7M
CPU: 1min 14.260s
CGroup: /system.slice/birdnet_stats.service
└─511 /home/pi/BirdNET-Pi/birdnet/bin/python3 /home/pi/BirdNET-Pi/birdnet/bin/streamlit run /home/pi/BirdNET-Pi/scripts/plotly_streamlit.py --browser.gatherUsageStats false --server.address localhost --server.baseUrlPath /stats

May 21 01:00:18 birdnet systemd[1]: Started BirdNET Stats.
May 21 01:00:37 birdnet streamlit[511]: You can now view your Streamlit app in your browser.
May 21 01:00:37 birdnet streamlit[511]: URL: http://localhost:8501/stats

========== chart_viewer status ==========
● chart_viewer.service - BirdNET-Pi Chart Viewer Service
Loaded: loaded (/home/pi/BirdNET-Pi/templates/chart_viewer.service; enabled; vendor preset: enabled)
Active: activating (auto-restart) since Sun 2023-05-21 15:12:52 MST; 42s ago
Process: 725193 ExecStart=/home/pi/BirdNET-Pi/birdnet/bin/python3 /usr/local/bin/daily_plot.py (code=exited, status=0/SUCCESS)
Main PID: 725193 (code=exited, status=0/SUCCESS)
CPU: 38.807s

May 21 15:12:52 birdnet systemd[1]: chart_viewer.service: Consumed 38.807s CPU time.

========== extraction status ==========
● extraction.service - BirdNET BirdSound Extraction
Loaded: loaded (/home/pi/BirdNET-Pi/templates/extraction.service; enabled; vendor preset: enabled)
Active: active (running) since Sun 2023-05-21 01:00:19 MST; 14h ago
Main PID: 526 (bash)
Tasks: 2 (limit: 1991)
Memory: 81.2M
CPU: 2h 26min 4.456s
CGroup: /system.slice/extraction.service
├─ 526 bash -c while true;do extract_new_birdsounds.sh;sleep 3;done
└─726912 sleep 3

May 21 15:10:12 birdnet env[723651]: Line = 3.0;6.0;Auriparus flaviceps;Verdin;0.8028962
May 21 15:10:12 birdnet env[723651]: NEWFILE=Verdin-80-2023-05-21-birdnet-15:09:32.mp3
May 21 15:10:32 birdnet env[724005]: Line = 2023-05-21-birdnet-15:09:57.wav.csv
May 21 15:11:05 birdnet env[724365]: Line = 2023-05-21-birdnet-15:10:23.wav.csv
May 21 15:11:24 birdnet env[724809]: Line = 2023-05-21-birdnet-15:10:48.wav.csv
May 21 15:11:46 birdnet env[725120]: Line = 2023-05-21-birdnet-15:11:13.wav.csv
May 21 15:12:23 birdnet env[725780]: Line = 2023-05-21-birdnet-15:11:38.wav.csv
May 21 15:12:43 birdnet env[726003]: Line = 2023-05-21-birdnet-15:12:03.wav.csv
May 21 15:13:01 birdnet env[726284]: Line = 2023-05-21-birdnet-15:12:28.wav.csv
May 21 15:13:29 birdnet env[726828]: Line = 2023-05-21-birdnet-15:12:53.wav.csv

========== web_terminal status ==========
● web_terminal.service - BirdNET-Pi Web Terminal
Loaded: loaded (/home/pi/BirdNET-Pi/templates/web_terminal.service; enabled; vendor preset: enabled)
Active: active (running) since Sun 2023-05-21 01:00:19 MST; 14h ago
Main PID: 562 (gotty)
Tasks: 9 (limit: 1991)
Memory: 1.2M
CPU: 90ms
CGroup: /system.slice/web_terminal.service
└─562 /usr/local/bin/gotty --address localhost -w -p 8888 -P terminal --title-format BirdNET-Pi Terminal login

May 21 01:00:19 birdnet systemd[1]: Started BirdNET-Pi Web Terminal.
May 21 01:00:20 birdnet gotty[562]: 2023/05/21 01:00:19 GoTTY is starting with command: login
May 21 01:00:20 birdnet gotty[562]: 2023/05/21 01:00:19 Permitting clients to write input to the PTY.
May 21 01:00:20 birdnet gotty[562]: 2023/05/21 01:00:19 HTTP server is listening at: http://127.0.0.1:8888/terminal/

========== spectrogram_viewer status ==========
● spectrogram_viewer.service - BirdNET-Pi Spectrogram Viewer
Loaded: loaded (/home/pi/BirdNET-Pi/templates/spectrogram_viewer.service; enabled; vendor preset: enabled)
Active: active (running) since Sun 2023-05-21 01:00:19 MST; 14h ago
Main PID: 546 (bash)
Tasks: 2 (limit: 1991)
Memory: 2.8M
CPU: 44min 25.663s
CGroup: /system.slice/spectrogram_viewer.service
├─ 546 bash /usr/local/bin/spectrogram.sh
└─726871 sleep 6

May 21 01:00:19 birdnet systemd[1]: Started BirdNET-Pi Spectrogram Viewer.
May 21 06:08:44 birdnet spectrogram.sh[263702]: sox FAIL formats: can't open input file `/home/pi/BirdSongs/May-2023/21-Sunday/2023-05-21-birdnet-06:08:11.wav': No such file or directory

========== livestream status ==========
● livestream.service - BirdNET-Pi Live Stream
Loaded: loaded (/home/pi/BirdNET-Pi/templates/livestream.service; enabled; vendor preset: enabled)
Active: activating (auto-restart) (Result: exit-code) since Sun 2023-05-21 15:13:32 MST; 2s ago
Process: 726875 ExecStart=/usr/local/bin/livestream.sh (code=exited, status=1/FAILURE)
Main PID: 726875 (code=exited, status=1/FAILURE)
CPU: 779ms

========= Syslog snippet ==========
May 21 15:12:58 birdnet birdnet_analysis.sh[726218]: 6.0;9.0-("Cardellina pusilla_Wilson's Warbler", 0.20141299)
May 21 15:12:58 birdnet birdnet_analysis.sh[726218]: 9.0;12.0-('Basileuterus rufifrons_Rufous-capped Warbler', 0.14662994)
May 21 15:12:58 birdnet birdnet_analysis.sh[726218]: 12.0;15.0-('Human_Human', 0.0)
May 21 15:12:58 birdnet birdnet_analysis.sh[726218]: 15.0;18.0-('Engine_Engine', 0.07318335)
May 21 15:12:58 birdnet birdnet_analysis.sh[726218]: 18.0;21.0-('Engine_Engine', 0.14864114)
May 21 15:12:58 birdnet birdnet_analysis.sh[726218]: 21.0;24.0-('Engine_Engine', 0.06379417)
May 21 15:12:58 birdnet systemd[1]: birdnet_analysis.service: Succeeded.
May 21 15:12:58 birdnet systemd[1]: birdnet_analysis.service: Consumed 1.259s CPU time.
May 21 15:12:59 birdnet systemd[1]: livestream.service: Scheduled restart job, restart counter is at 12556.
May 21 15:12:59 birdnet systemd[1]: Stopped BirdNET-Pi Live Stream.
May 21 15:12:59 birdnet systemd[1]: Started BirdNET-Pi Live Stream.
May 21 15:13:00 birdnet systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 1938.
May 21 15:13:00 birdnet systemd[1]: Stopped BirdNET Analysis.
May 21 15:13:00 birdnet systemd[1]: birdnet_analysis.service: Consumed 1.259s CPU time.
May 21 15:13:00 birdnet systemd[1]: Started BirdNET Analysis.
May 21 15:13:00 birdnet livestream.sh[726264]: [tcp @ 0xaaaae7630a10] Connection to tcp://localhost:8000 failed: Connection refused
May 21 15:13:00 birdnet livestream.sh[726264]: icecast://source:birdnetpi@localhost:8000/stream: Connection refused
May 21 15:13:00 birdnet systemd[1]: livestream.service: Main process exited, code=exited, status=1/FAILURE
May 21 15:13:00 birdnet systemd[1]: livestream.service: Failed with result 'exit-code'.
May 21 15:13:01 birdnet birdnet_analysis.sh[726276]: Files loaded
May 21 15:13:01 birdnet env[726284]: Line = 2023-05-21-birdnet-15:12:28.wav.csv
May 21 15:13:02 birdnet birdnet_analysis.sh[726276]: RECORDING_LENGTH set to 25
May 21 15:13:03 birdnet systemd[1]: livestream.service: Scheduled restart job, restart counter is at 12557.
May 21 15:13:03 birdnet systemd[1]: Stopped BirdNET-Pi Live Stream.
May 21 15:13:03 birdnet systemd[1]: Started BirdNET-Pi Live Stream.
May 21 15:13:04 birdnet livestream.sh[726369]: [tcp @ 0xaaaac9498a10] Connection to tcp://localhost:8000 failed: Connection refused
May 21 15:13:04 birdnet livestream.sh[726369]: icecast://source:birdnetpi@localhost:8000/stream: Connection refused
May 21 15:13:04 birdnet systemd[1]: livestream.service: Main process exited, code=exited, status=1/FAILURE
May 21 15:13:04 birdnet systemd[1]: livestream.service: Failed with result 'exit-code'.
May 21 15:13:07 birdnet systemd[1]: livestream.service: Scheduled restart job, restart counter is at 12558.
May 21 15:13:07 birdnet systemd[1]: Stopped BirdNET-Pi Live Stream.
May 21 15:13:07 birdnet systemd[1]: Started BirdNET-Pi Live Stream.
May 21 15:13:08 birdnet livestream.sh[726418]: [tcp @ 0xaaaade431a10] Connection to tcp://localhost:8000 failed: Connection refused
May 21 15:13:08 birdnet livestream.sh[726418]: icecast://source:birdnetpi@localhost:8000/stream: Connection refused
May 21 15:13:08 birdnet systemd[1]: livestream.service: Main process exited, code=exited, status=1/FAILURE
May 21 15:13:08 birdnet systemd[1]: livestream.service: Failed with result 'exit-code'.
May 21 15:13:11 birdnet systemd[1]: livestream.service: Scheduled restart job, restart counter is at 12559.
May 21 15:13:11 birdnet systemd[1]: Stopped BirdNET-Pi Live Stream.
May 21 15:13:11 birdnet systemd[1]: Started BirdNET-Pi Live Stream.
May 21 15:13:13 birdnet livestream.sh[726466]: [tcp @ 0xaaaac972aa10] Connection to tcp://localhost:8000 failed: Connection refused
May 21 15:13:18 birdnet livestream.sh[726466]: icecast://source:birdnetpi@localhost:8000/stream: Connection refused
May 21 15:13:18 birdnet systemd[1]: livestream.service: Main process exited, code=exited, status=1/FAILURE
May 21 15:13:18 birdnet systemd[1]: livestream.service: Failed with result 'exit-code'.
May 21 15:13:18 birdnet systemd[1]: livestream.service: Scheduled restart job, restart counter is at 12560.
May 21 15:13:18 birdnet livestream.sh[726504]: [tcp @ 0xaaaad2149a10] Connection to tcp://localhost:8000 failed: Connection refused
May 21 15:13:18 birdnet livestream.sh[726504]: icecast://source:birdnetpi@localhost:8000/stream: Connection refused
May 21 15:13:18 birdnet systemd[1]: Stopped BirdNET-Pi Live Stream.
May 21 15:13:18 birdnet systemd[1]: Started BirdNET-Pi Live Stream.
May 21 15:13:18 birdnet systemd[1]: livestream.service: Main process exited, code=exited, status=1/FAILURE
May 21 15:13:18 birdnet systemd[1]: livestream.service: Failed with result 'exit-code'.
May 21 15:13:19 birdnet birdnet_analysis.sh[726276]: /home/pi/BirdNET-Pi/birdnet/bin/python3 /home/pi/BirdNET-Pi/scripts/analyze.py --i /home/pi/BirdSongs/May-2023/21-Sunday/2023-05-21-birdnet-15:12:53.wav --o /home/pi/BirdSongs/May-2023/21-Sunday/2023-05-21-birdnet-15:12:53.wav.csv --lat 32.XX --lon -110.XX --week 19 --overlap 0.0 --sensitivity 1.25 --min_conf 0.7 --birdweather_id "IN_USE"
May 21 15:13:19 birdnet python3[509]: ANALYZING AUDIO... DONE! Time 3.2 SECONDS
May 21 15:13:19 birdnet python3[509]: WRITING RESULTS TO /home/pi/BirdSongs/May-2023/21-Sunday/2023-05-21-birdnet-15:12:28.wav.csv ... DONE! WROTE 0 RESULTS.
May 21 15:13:19 birdnet python3[509]: READING AUDIO DATA... DONE! READ 8 CHUNKS.
May 21 15:13:19 birdnet python3[509]: Date-time: 2023-05-21 15:12:53
May 21 15:13:19 birdnet systemd[1]: livestream.service: Scheduled restart job, restart counter is at 12561.
May 21 15:13:19 birdnet systemd[1]: Stopped BirdNET-Pi Live Stream.
May 21 15:13:19 birdnet systemd[1]: Started BirdNET-Pi Live Stream.
May 21 15:13:20 birdnet livestream.sh[726683]: [tcp @ 0xaaaaffb0fa10] Connection to tcp://localhost:8000 failed: Connection refused
May 21 15:13:20 birdnet livestream.sh[726683]: icecast://source:birdnetpi@localhost:8000/stream: Connection refused
May 21 15:13:20 birdnet systemd[1]: livestream.service: Main process exited, code=exited, status=1/FAILURE
May 21 15:13:20 birdnet systemd[1]: livestream.service: Failed with result 'exit-code'.
May 21 15:13:22 birdnet birdnet_analysis.sh[726680]: 0.0;3.0-('Engine_Engine', 0.080875725)
May 21 15:13:22 birdnet birdnet_analysis.sh[726680]: 3.0;6.0-('Basileuterus rufifrons_Rufous-capped Warbler', 0.18281293)
May 21 15:13:22 birdnet birdnet_analysis.sh[726680]: 6.0;9.0-('Parkesia motacilla_Louisiana Waterthrush', 0.22342077)
May 21 15:13:22 birdnet birdnet_analysis.sh[726680]: 9.0;12.0-('Engine_Engine', 0.070943035)
May 21 15:13:22 birdnet birdnet_analysis.sh[726680]: 12.0;15.0-('Engine_Engine', 0.068616815)
May 21 15:13:22 birdnet birdnet_analysis.sh[726680]: 15.0;18.0-('Human_Human', 0.0)
May 21 15:13:22 birdnet birdnet_analysis.sh[726680]: 18.0;21.0-('Zenaida macroura_Mourning Dove', 0.6351412)
May 21 15:13:22 birdnet birdnet_analysis.sh[726680]: 21.0;24.0-('Auriparus flaviceps_Verdin', 0.40025303)
May 21 15:13:22 birdnet systemd[1]: birdnet_analysis.service: Succeeded.
May 21 15:13:22 birdnet systemd[1]: birdnet_analysis.service: Consumed 1.496s CPU time.
May 21 15:13:23 birdnet systemd[1]: livestream.service: Scheduled restart job, restart counter is at 12562.
May 21 15:13:23 birdnet systemd[1]: Stopped BirdNET-Pi Live Stream.
May 21 15:13:23 birdnet systemd[1]: Started BirdNET-Pi Live Stream.
May 21 15:13:24 birdnet livestream.sh[726722]: [tcp @ 0xaaab07f3aa10] Connection to tcp://localhost:8000 failed: Connection refused
May 21 15:13:24 birdnet livestream.sh[726722]: icecast://source:birdnetpi@localhost:8000/stream: Connection refused
May 21 15:13:24 birdnet systemd[1]: livestream.service: Main process exited, code=exited, status=1/FAILURE
May 21 15:13:24 birdnet systemd[1]: livestream.service: Failed with result 'exit-code'.
May 21 15:13:25 birdnet systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 1939.
May 21 15:13:25 birdnet systemd[1]: Stopped BirdNET Analysis.
May 21 15:13:25 birdnet systemd[1]: birdnet_analysis.service: Consumed 1.496s CPU time.
May 21 15:13:25 birdnet systemd[1]: Started BirdNET Analysis.
May 21 15:13:26 birdnet birdnet_analysis.sh[726738]: Files loaded
May 21 15:13:26 birdnet birdnet_analysis.sh[726738]: RECORDING_LENGTH set to 25
May 21 15:13:27 birdnet systemd[1]: livestream.service: Scheduled restart job, restart counter is at 12563.
May 21 15:13:27 birdnet systemd[1]: Stopped BirdNET-Pi Live Stream.
May 21 15:13:27 birdnet systemd[1]: Started BirdNET-Pi Live Stream.
May 21 15:13:28 birdnet livestream.sh[726819]: [tcp @ 0xaaaade9f0a10] Connection to tcp://localhost:8000 failed: Connection refused
May 21 15:13:28 birdnet livestream.sh[726819]: icecast://source:birdnetpi@localhost:8000/stream: Connection refused
May 21 15:13:28 birdnet systemd[1]: livestream.service: Main process exited, code=exited, status=1/FAILURE
May 21 15:13:28 birdnet systemd[1]: livestream.service: Failed with result 'exit-code'.
May 21 15:13:29 birdnet env[726828]: Line = 2023-05-21-birdnet-15:12:53.wav.csv
May 21 15:13:31 birdnet systemd[1]: livestream.service: Scheduled restart job, restart counter is at 12564.
May 21 15:13:31 birdnet systemd[1]: Stopped BirdNET-Pi Live Stream.
May 21 15:13:31 birdnet systemd[1]: Started BirdNET-Pi Live Stream.
May 21 15:13:32 birdnet livestream.sh[726876]: [tcp @ 0xaaaadc366a10] Connection to tcp://localhost:8000 failed: Connection refused
May 21 15:13:32 birdnet livestream.sh[726876]: icecast://source:birdnetpi@localhost:8000/stream: Connection refused
May 21 15:13:32 birdnet systemd[1]: livestream.service: Main process exited, code=exited, status=1/FAILURE
May 21 15:13:32 birdnet systemd[1]: livestream.service: Failed with result 'exit-code'.
========== Mounted File Systems ==========
Filesystem Size Used Avail Use% Mounted on
udev 830M 0 830M 0% /dev
tmpfs 193M 1.2M 192M 1% /run
/dev/mmcblk1p2 29G 26G 2.0G 94% /
tmpfs 965M 0 965M 0% /dev/shm
tmpfs 5.0M 4.0K 5.0M 1% /run/lock
/dev/mmcblk1p1 255M 128M 128M 51% /boot
tmpfs 193M 4.0K 193M 1% /run/user/1000

========== Memory Usage ==========
total used free shared buff/cache available
Mem: 1.9Gi 565Mi 194Mi 8.0Mi 1.1Gi 1.3Gi
Swap: 199Mi 193Mi 6.0Mi

========== Load Averages ==========
15:13:35 up 14:12, 2 users, load average: 4.20, 3.73, 3.42

========== CPU Info ==========
processor : 0
BogoMIPS : 48.00
Features : fp asimd evtstrm aes pmull sha1 sha2 crc32 cpuid
CPU implementer : 0x41
CPU architecture: 8
CPU variant : 0x0
CPU part : 0xd03
CPU revision : 4

processor : 1
BogoMIPS : 48.00
Features : fp asimd evtstrm aes pmull sha1 sha2 crc32 cpuid
CPU implementer : 0x41
CPU architecture: 8
CPU variant : 0x0
CPU part : 0xd03
CPU revision : 4

processor : 2
BogoMIPS : 48.00
Features : fp asimd evtstrm aes pmull sha1 sha2 crc32 cpuid
CPU implementer : 0x41
CPU architecture: 8
CPU variant : 0x0
CPU part : 0xd03
CPU revision : 4

processor : 3
BogoMIPS : 48.00
Features : fp asimd evtstrm aes pmull sha1 sha2 crc32 cpuid
CPU implementer : 0x41
CPU architecture: 8
CPU variant : 0x0
CPU part : 0xd03
CPU revision : 4

========== System Temperature ==========
CPU Temperature: 53.00°C / 127.40°F

========== Extra Info ==========
........................................IPs.....................................
LAN IP: 10.0.0.154
Public IP: 69.244.8.81
..................................vcgencmd stats..............................
VCHI initialization failed
VCHI initialization failed
Binary:
....................................Clock Speeds................................
VCHI initialization failed
arm:
VCHI initialization failed
core:
VCHI initialization failed
h264:
VCHI initialization failed
isp:
VCHI initialization failed
v3d:
VCHI initialization failed
uart:
VCHI initialization failed
pwm:
VCHI initialization failed
emmc:
VCHI initialization failed
pixel:
VCHI initialization failed
vec:
VCHI initialization failed
hdmi:
VCHI initialization failed
dpi:
........................................Volts...................................
VCHI initialization failed
core:
VCHI initialization failed
sdram_c:
VCHI initialization failed
sdram_i:
VCHI initialization failed
sdram_p:
.....................................Caddyfile..................................
http:// http://FallenBirdNet.sytes.net {
root * /home/pi/BirdSongs/Extracted
file_server browse
handle /By_Date/* {
file_server browse
}
handle /Charts/* {
file_server browse
}
basicauth /views.php?view=File* {
birdnet $2a$14$Vdxc6TECvVfXioeeP56eL.gtavsMDPW1KfiLJKfX7xwMsP45M9Cui
}
basicauth /Processed* {
birdnet $2a$14$Vdxc6TECvVfXioeeP56eL.gtavsMDPW1KfiLJKfX7xwMsP45M9Cui
}
basicauth /scripts* {
birdnet $2a$14$Vdxc6TECvVfXioeeP56eL.gtavsMDPW1KfiLJKfX7xwMsP45M9Cui
}
basicauth /stream {
birdnet $2a$14$Vdxc6TECvVfXioeeP56eL.gtavsMDPW1KfiLJKfX7xwMsP45M9Cui
}
basicauth /phpsysinfo* {
birdnet $2a$14$Vdxc6TECvVfXioeeP56eL.gtavsMDPW1KfiLJKfX7xwMsP45M9Cui
}
basicauth /terminal* {
birdnet $2a$14$Vdxc6TECvVfXioeeP56eL.gtavsMDPW1KfiLJKfX7xwMsP45M9Cui
}
reverse_proxy /stream localhost:8000
php_fastcgi unix//run/php/php7.4-fpm.sock
reverse_proxy /log* localhost:8080
reverse_proxy /stats* localhost:8501
reverse_proxy /terminal* localhost:8888
}
.................................... Crontab....................................

SHELL=/bin/sh
PATH=/usr/local/sbin:/usr/local/bin:/sbin:/bin:/usr/sbin:/usr/bin

17 * * * * root cd / && run-parts --report /etc/cron.hourly
25 6 * * * root test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.daily )
47 6 * * 7 root test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.weekly )
52 6 1 * * root test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.monthly )
*/5 * * * * pi /usr/local/bin/disk_check.sh >/dev/null 2>&1
*/3 * * * * pi /usr/local/bin/cleanup.sh >/dev/null 2>&1
@reboot pi /usr/local/bin/cleanup.sh >/dev/null 2>&1
0 1 * * 1 pi /usr/local/bin/weekly_report.sh >/dev/null 2>&1

========== Connected Microphone Devices ==========
**** List of CAPTURE Hardware Devices ****
card 1: BIGFOOT [BIGFOOT], device 0: USB Audio [USB Audio]
Subdevices: 0/1
Subdevice #0: subdevice #0

null
Discard all samples (playback) or generate zero samples (capture)
default
Playback/recording through the PulseAudio sound server
lavrate
Rate Converter Plugin Using Libav/FFmpeg Library
samplerate
Rate Converter Plugin Using Samplerate Library
speexrate
Rate Converter Plugin Using Speex Resampler
jack
JACK Audio Connection Kit
oss
Open Sound System
pulse
PulseAudio Sound Server
upmix
Plugin for channel upmix (4,6,8)
vdownmix
Plugin for channel downmix (stereo) with a simple spacialization
usbstream:CARD=LIBRETECHCC
LIBRETECH-CC
USB Stream Output
hw:CARD=BIGFOOT,DEV=0
BIGFOOT, USB Audio
Direct hardware device without any conversions
plughw:CARD=BIGFOOT,DEV=0
BIGFOOT, USB Audio
Hardware device with all software conversions
sysdefault:CARD=BIGFOOT
BIGFOOT, USB Audio
Default Audio Device
front:CARD=BIGFOOT,DEV=0
BIGFOOT, USB Audio
Front output / input
dsnoop:CARD=BIGFOOT,DEV=0
BIGFOOT, USB Audio
Direct sample snooping device
usbstream:CARD=BIGFOOT
BIGFOOT
USB Stream Output

========= Date and Time ==========
Sun May 21 15:13:36 MST 2023

pi@birdnet:~/BirdNET-Pi/scripts$

ehpersonal38 May 21, 2023
Collaborator

I see that your Extraction Service is creating new spectrograms, so that means you're getting detections as normal now, or not? Is the problem just the Livestream or the detections too?

jmtuts May 21, 2023
Author

I am getting detection, not sure if they are at the same rate, here is the last 12 days. There does seem to be a drop starting on the 14th. I am still getting the livestream error when running journalctl -f

May 10 - 1239
May 11 - 1502
May 12 - 1589
May 13 - 1250
May 14 - 803
May 15 - 601
May 16 - 604
May 17 - 1 (not sure what happened here)
May 18 - 474
May 19 - 232
May 20 - 635
May 21 - 363 (Still 7.5 hours left in the day)

lloydbayley · 2023-05-22T06:59:22Z

lloydbayley
May 22, 2023

I'm confused now. Initially, you said you were getting 0 detections. Did this correct itself?

7 replies

jmtuts May 22, 2023
Author

I would have to say "maybe" to the firmware question. Monthly I run the following command (sudo apt-get update && sudo apt-get dist-upgrade) to keep the OS up to date, not sure if this includes firmware upgrades. I power cycled the Libre on the 17th when I changed out the mic.

lloydbayley May 22, 2023

I doubt that would have messed with any firmware. You really have to tell it to. With the dist-upgrade, it's possible that it changed something. It's hard to tell without being able to reverse it and test.
It's a good idea to do a full backup before upgrades, especially dist-upgrades.
I know this doesn't really help your current situation but as I said, I'm struggling to work it out.
I'm starting to think backing up your stuff and doing a fresh install might be an idea.

jmtuts May 22, 2023
Author

I don't believe there is a way to back out the changes, so that is a no go. Would a rebuild have any impact?
As far as I know the errors are not creating any issues besides the lower amount of detections historically, but there are too many factors involved to say that specifically the livestream errors are the cause of my lowered detections. Basically I just don't like seeing errors

lloydbayley May 22, 2023

I agree. Correct, there is no way to downgrade it and that's what makes it tricky to diagnose.
Errors give me the irits too, so I know what you mean (you should see my Home Assistant log - but that's got to do with my 'meddling' and doesn't actually affect anytyhing!) - I hate seeing them there, so I rarely look.
Yes, it does make it hard to diagnose with the factors. If it were me, I'd image the SD card so I had a backup or try a fresh install on another card. (An image is always a good idea). Just to see if the problem 'magically' goes away.

jmtuts May 24, 2023
Author

Interesting, with the latest batch of pushes upon updating the Livestream failures are gone. Who knew?

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Livestream Failures #917

{{title}}

Replies: 4 comments 16 replies

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{title}}

Select a reply

Livestream Failures #917

Replies: 4 comments · 16 replies

jmtuts May 20, 2023 Author

jmtuts Aug 9, 2023 Author

jmtuts May 20, 2023 Author

ehpersonal38 May 21, 2023 Collaborator

jmtuts May 21, 2023 Author

========= Date and Time ========== Sun May 21 15:13:36 MST 2023

ehpersonal38 May 21, 2023 Collaborator

jmtuts May 21, 2023 Author

jmtuts May 22, 2023 Author

jmtuts May 22, 2023 Author

jmtuts May 24, 2023 Author

Replies: 4 comments 16 replies

jmtuts May 20, 2023
Author

jmtuts Aug 9, 2023
Author

jmtuts
May 20, 2023
Author

ehpersonal38
May 21, 2023
Collaborator

jmtuts May 21, 2023
Author

========= Date and Time ==========
Sun May 21 15:13:36 MST 2023

ehpersonal38 May 21, 2023
Collaborator

jmtuts May 21, 2023
Author

jmtuts May 22, 2023
Author

jmtuts May 22, 2023
Author

jmtuts May 24, 2023
Author