search

mcguirepr89 / BirdNET-Pi

A realtime acoustic bird classification system for the Raspberry Pi 4B, 3B+, and 0W2 built on the TFLite version of BirdNET.
Other
1.3k stars 139 forks source link

Error with audio? #428

Closed b0rnwr0ng closed 2 years ago

b0rnwr0ng commented 2 years ago

Screenshot_20220707-115321_Chrome

Sorry i dont have an easy way to post the error, but doesnt seem to restart, no observations today. Only thing other than updating was enabled the reporting using discord that i can recall.

b0rnwr0ng commented 2 years ago

Yeah was thinking this too, or possibly an updated version. It is installed though:

ffmpeg version 4.3.4-0+deb11u1+rpt2 Copyright (c) 2000-2021 the FFmpeg developers built with gcc 10 (Debian 10.2.1-6)

mcguirepr89 commented 2 years ago

Hi, @b0rnwr0ng

Could you please access the installation's shell, either via SSH or through "Tools" > "Web Terminal" and run the following commands sending me the output for each?

  1. restart_services.sh
  2. journalctl --no-hostname --no-pager -n100 -u birdnet_analysis -u birdnet_server -u birdnet_recording | sed "s|$HOME||g"
  3. df -h && free -h

Hopefully there will be some helpful information somewhere within those logs and command outputs to move forward with troubleshooting or resolve your issue.

My best, Patrick

b0rnwr0ng commented 2 years ago

birdnetpi:~/BirdNET-Pi$ journalctl --no-hostname --no-pager -n100 -u birdnet_analysis -u birdnet_server -u birdnet_recording | sed "s|$HOME||g" -- Journal begins at Thu 2022-06-30 06:51:39 EDT, ends at Thu 2022-07-07 17:14:19 EDT. -- Jul 07 16:06:35 birdnet_recording.sh[538]: W: [autospawn] lock-autospawn.c: Cannot access autospawn lock. Jul 07 16:06:35 birdnet_recording.sh[538]: E: [pulseaudio] main.c: Failed to acquire autospawn lock Jul 07 16:06:35 birdnet_recording.sh[402]: + pgrep arecord Jul 07 16:06:35 birdnet_recording.sh[402]: + grep 5050 /dev/fd/63 Jul 07 16:06:35 birdnet_recording.sh[569]: ++ netstat -tulpn Jul 07 16:06:35 birdnet_recording.sh[402]: + sleep 1 Jul 07 16:06:35 systemd[1]: Stopping BirdNET Recording... Jul 07 16:06:35 systemd[1]: birdnet_recording.service: Succeeded. Jul 07 16:06:35 systemd[1]: Stopped BirdNET Recording. Jul 07 16:06:35 systemd[1]: Stopping BirdNET Analysis... Jul 07 16:06:35 systemd[1]: birdnet_analysis.service: Succeeded. Jul 07 16:06:35 systemd[1]: Stopped BirdNET Analysis. Jul 07 16:06:35 systemd[1]: Stopping BirdNET Analysis Server... Jul 07 16:06:35 systemd[1]: birdnet_server.service: Succeeded. Jul 07 16:06:35 systemd[1]: Stopped BirdNET Analysis Server. Jul 07 17:14:03 systemd[1]: Started BirdNET Recording. Jul 07 17:14:03 birdnet_recording.sh[6240]: + source /etc/birdnet/birdnet.conf Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ LATITUDE= Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ LONGITUDE= Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ CADDY_PWD Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ ICE_PWD= Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ BIRDNETPI_URL=http://.... Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ PUSHED_APP_KEY= Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ PUSHED_APP_SECRET= Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ RECS_DIR=/BirdSongs Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ REC_CARD=default Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ PROCESSED=/BirdSongs/Processed Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ EXTRACTED=/BirdSongs/Extracted Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ OVERLAP=0.0 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ CONFIDENCE=0.65 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ SENSITIVITY=1.25 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ CHANNELS=2 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ FULL_DISK=purge Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ PRIVACY_MODE=on Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ RECORDING_LENGTH=15 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ EXTRACTION_LENGTH= Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ AUDIOFMT=mp3 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ LAST_RUN= Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ THIS_RUN= Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ IDFILE=/BirdNET-Pi/IdentifiedSoFar.txt Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ PRIVACY_THRESHOLD=3 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ APPRISE_NOTIFICATION_TITLE='New BirdNET-Pi Detection' Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ APPRISE_NOTIFICATION_BODY='A $sciname $comname was just detected with a confidence of $confidence' Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ APPRISE_NOTIFY_EACH_DETECTION=0 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ RTSP_STREAM= Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ APPRISE_NOTIFY_NEW_SPECIES=1 Jul 07 17:14:03 birdnet_recording.sh[6240 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ DATABASE_LANG=en Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ FLICKR_FILTER_EMAIL= Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ APPRISE_NOTIFY_NEW_SPECIES_EACH_DAY=1 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ APPRISE_WEEKLY_REPORT=1 Jul 07 17:14:03 birdnet_recording.sh[6240]: ++ SILENCE_UPDATE_INDICATOR=0 Jul 07 17:14:03 birdnet_recording.sh[6240]: + '[' -z 15 ']' Jul 07 17:14:03 birdnet_recording.sh[6240]: + '[' '!' -z ']' Jul 07 17:14:03 birdnet_recording.sh[6240]: + pulseaudio --check Jul 07 17:14:03 birdnet_recording.sh[6240]: + pgrep arecord Jul 07 17:14:03 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:03 birdnet_recording.sh[6254]: ++ netstat -tulpn Jul 07 17:14:03 birdnet_recording.sh[6240]: + sleep 1 Jul 07 17:14:03 systemd[1]: Started BirdNET Analysis Server. Jul 07 17:14:04 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:04 birdnet_recording.sh[6281]: ++ netstat -tulpn Jul 07 17:14:04 birdnet_recording.sh[6240]: + sleep 1 Jul 07 17:14:05 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:05 birdnet_recording.sh[6291]: ++ netstat -tulpn Jul 07 17:14:05 birdnet_recording.sh[6240]: + sleep 1 Jul 07 17:14:06 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:06 birdnet_recording.sh[6295]: ++ netstat -tulpn Jul 07 17:14:06 birdnet_recording.sh[6240]: + sleep 1 Jul 07 17:14:07 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:07 birdnet_recording.sh[6320]: ++ netstat -tulpn Jul 07 17:14:07 birdnet_recording.sh[6240]: + sleep 1 Jul 07 17:14:08 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:08 birdnet_recording.sh[6331]: ++ netstat -tulpn Jul 07 17:14:08 birdnet_recording.sh[6240]: + sleep 1 Jul 07 17:14:09 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:09 birdnet_recording.sh[6337]: ++ netstat -tulpn Jul 07 17:14:09 birdnet_recording.sh[6240]: + sleep 1 Jul 07 17:14:10 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:10 birdnet_recording.sh[6362]: ++ netstat -tulpn Jul 07 17:14:10 birdnet_recording.sh[6240]: + sleep 1 Jul 07 17:14:11 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:11 birdnet_recording.sh[6366]: ++ netstat -tulpn Jul 07 17:14:11 birdnet_recording.sh[6240]: + sleep 1 Jul 07 17:14:12 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:12 birdnet_recording.sh[6373]: ++ netstat -tulpn Jul 07 17:14:12 birdnet_recording.sh[6240]: + sleep 1 Jul 07 17:14:13 python3[6260]: INFO: Created TensorFlow Lite XNNPACK delegate for CPU. Jul 07 17:14:13 python3[6260]: INFO: Created TensorFlow Lite delegate for select TF ops. Jul 07 17:14:13 python3[6260]: INFO: TfLiteFlexDelegate delegate: 1 nodes delegated out of 182 nodes with 1 partitions. Jul 07 17:14:13 birdnet_recording.sh[6240]: + grep 5050 /dev/fd/63 Jul 07 17:14:13 birdnet_recording.sh[6399]: ++ netstat -tulpn Jul 07 17:14:14 birdnet_recording.sh[6400]: tcp 0 0 127.0.0.1:5050 0.0.0.0:* LISTEN 6260/python3 Jul 07 17:14:14 birdnet_recording.sh[6240]: + '[' -z default ']' Jul 07 17:14:14 birdnet_recording.sh[6240]: + arecord -f S16_LE -c2 -r48000 -t wav --max-file-time 15 -D default --use-strftime /BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav Jul 07 17:14:14 systemd[1]: Started BirdNET Analysis. Jul 07 17:14:14 birdnet_recording.sh[6403]: Recording WAVE '/BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav' : Signed 16 bit Little Endian, Rate 48000 Hz, Stereo Jul 07 17:14:14 birdnet_analysis.sh[6442]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 07 17:14:15 birdnet_analysis.sh[6405]: Files loaded birdnetpi:~/BirdNET-Pi$ df -h && free -h Filesystem Size Used Avail Use% Mounted on /dev/root 15G 11G 3.6G 75% / devtmpfs 1.7G 0 1.7G 0% /dev tmpfs 1.9G 0 1.9G 0% /dev/shm tmpfs 759M 912K 759M 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock /dev/mmcblk0p1 253M 31M 222M 12% /boot tmpfs 380M 4.0K 380M 1% /run/user/1000 total used free shared buff/cache available Mem: 3.7Gi 318Mi 2.7Gi 3.0Mi 737Mi 3.3Gi Swap: 99Mi 0B 99Mi birdnetpi:~/BirdNET-

Get Outlook for Androidhttps://aka.ms/AAb9ysg

From: Patrick McGuire @.> Sent: Thursday, July 7, 2022 4:55:32 PM To: mcguirepr89/BirdNET-Pi @.> Cc: b0rnwr0ng @.>; Mention @.> Subject: Re: [mcguirepr89/BirdNET-Pi] Error with audio? (Issue #428)

Hi, @b0rnwr0nghttps://github.com/b0rnwr0ng

Could you please access the installation's shell, either via SSH or through "Tools" > "Web Terminal" and run the following commands sending me the output for each?

1.

restart_services.sh

2.

journalctl --no-hostname --no-pager -n100 -u birdnet_analysis -u birdnet_server -u birdnet_recording | sed "s|$HOME||g"

3.

df -h && free -h

Hopefully there will be some helpful information somewhere within those logs and command outputs to move forward with troubleshooting or resolve your issue.

My best, Patrick

— Reply to this email directly, view it on GitHubhttps://github.com/mcguirepr89/BirdNET-Pi/issues/428#issuecomment-1178217531, or unsubscribehttps://github.com/notifications/unsubscribe-auth/ADTWEJAQUUNAW6D3RLTK3XDVS474JANCNFSM5255VPOQ. You are receiving this because you were mentioned.Message ID: @.***>

mcguirepr89 commented 2 years ago

Unfortunately, I don't see any unexpected errors from those logs. :thinking:

Does the "Currently Analyzing" spectrogram at the very bottom of the "Overview" page show audio content (be sure to check the timestamp on the image to see that it is current)?

b0rnwr0ng commented 2 years ago

Its showing Thurs a little past midnight, probably around the time i did the update

mcguirepr89 commented 2 years ago

Try "Tools" > "Services" > Restart Spectrogram Viewer and let me know if it begins updating

b0rnwr0ng commented 2 years ago

found a better way to paste the error

12:25:07---INFO: Created TensorFlow Lite delegate for select TF ops.
12:25:07---INFO: TfLiteFlexDelegate delegate: 1 nodes delegated out of 182 nodes with 1 partitions.
12:25:09---Files loaded
12:25:10---RECORDING_LENGTH set to 15
12:25:10---BirdNET-Pi/birdnet/bin/python3 BirdNET-Pi/scripts\analyze.py --i BirdSongs/July-2022/07-Thursday/2022-07-07-birdnet-00:57:05.wav --o BirdSongs/July-2022/07-Thursday/2022-07-07-birdnet-00:57:05.wav.csv --lat 40.XX --lon -74.XX --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE"
12:25:10---LOADING TF LITE MODEL... DONE!
12:25:10---READING AUDIO DATA... BirdNET-Pi/birdnet/lib/python3.9/site-packages/librosa/util/decorators.py:88: UserWarning: PySoundFile failed. Trying audioread instead.
12:25:10---  return f(*args, **kwargs)
12:25:11---Exception in thread Thread-1:
12:25:11---Traceback (most recent call last):
12:25:11---  File "BirdNET-Pi/birdnet/lib/python3.9/site-packages/librosa/core/audio.py", line 155, in load
12:25:11---    context = sf.SoundFile(path)
12:25:11---  File "BirdNET-Pi/birdnet/lib/python3.9/site-packages/soundfile.py", line 629, in __init__
12:25:11---    self._file = self._open(file, mode_int, closefd)
12:25:11---  File "BirdNET-Pi/birdnet/lib/python3.9/site-packages/soundfile.py", line 1183, in _open
12:25:11---    _error_check(_snd.sf_error(file_ptr),
12:25:11---  File "BirdNET-Pi/birdnet/lib/python3.9/site-packages/soundfile.py", line 1357, in _error_check
12:25:11---    raise RuntimeError(prefix + _ffi.string(err_str).decode('utf-8', 'replace'))
12:25:11---RuntimeError: Error opening 'BirdSongs/July-2022/07-Thursday/2022-07-07-birdnet-00:57:05.wav': Format not recognised.
12:25:11---During handling of the above exception, another exception occurred:
12:25:11---Traceback (most recent call last):
12:25:11---  File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner
12:25:11---    self.run()
12:25:11---  File "/usr/lib/python3.9/threading.py", line 892, in run
12:25:11---    self._target(*self._args, **self._kwargs)
12:25:11---  File "/usr/local/bin/server.py", line 321, in handle_client
12:25:11---    audioData = readAudioData(args.i, args.overlap)
12:25:11---  File "/usr/local/bin/server.py", line 129, in readAudioData
12:25:11---    sig, rate = librosa.load(path, sr=sample_rate, mono=True, res_type='kaiser_fast')
12:25:11---  File "BirdNET-Pi/birdnet/lib/python3.9/site-packages/librosa/util/decorators.py", line 88, in inner_f
12:25:11---    return f(*args, **kwargs)
12:25:11---  File "BirdNET-Pi/birdnet/lib/python3.9/site-packages/librosa/core/audio.py", line 174, in load
12:25:11---    y, sr_native = __audioread_load(path, offset, duration, dtype)
12:25:11---  File "BirdNET-Pi/birdnet/lib/python3.9/site-packages/librosa/core/audio.py", line 198, in __audioread_load
12:25:11---    with audioread.audio_open(path) as input_file:
12:25:11---  File "BirdNET-Pi/birdnet/lib/python3.9/site-packages/audioread/__init__.py", line 116, in audio_open
12:25:11---    raise NoBackendError()
12:25:11---audioread.exceptions.NoBackendError`
mcguirepr89 commented 2 years ago

Please let me know what results you observe based on my most recent comment -- we will move forward from there thank you

b0rnwr0ng commented 2 years ago

Did not work. I restarted core services after that and pasted results

mcguirepr89 commented 2 years ago

I believe pulseaudio is not starting.

Please provide the output from these commands -- please don't forget any of them:

  1. systemctl status getty@tty1.service
  2. journalctl --no-hostname --no-pager -n50 --user -upulseaudio

    (This command will have errors from the kernel due to a kernel bug, but we just want to see if it has started and what it says if it is not able to)

mcguirepr89 commented 2 years ago

I restarted core services after that and pasted results

Results from restarting the core services would look like:

birdnetpi1:~/BirdNET-Pi$ restart_services.sh 
+ my_dir=/home/birdie/BirdNET-Pi/scripts
+ sudo systemctl stop birdnet_server.service
+ sudo systemctl stop birdnet_recording.service
+ services=(chart_viewer.service spectrogram_viewer.service icecast2.service extraction.service birdnet_recording.service birdnet_log.service)
+ for i in "${services[@]}"
+ sudo systemctl restart chart_viewer.service
+ for i in "${services[@]}"
+ sudo systemctl restart spectrogram_viewer.service
+ for i in "${services[@]}"
+ sudo systemctl restart icecast2.service
+ for i in "${services[@]}"
+ sudo systemctl restart extraction.service
+ for i in "${services[@]}"
+ sudo systemctl restart birdnet_recording.service
+ for i in "${services[@]}"
+ sudo systemctl restart birdnet_log.service
+ sudo systemctl start birdnet_server.service
+ sleep 5
+ for i in {1..5}
+ systemctl is-active --quiet birdnet_server.service
+ grep 5050 /dev/fd/63
++ netstat -tulpn
tcp        0      0 127.0.0.1:5050          0.0.0.0:*               LISTEN      1516382/python3     
+ logger '[/usr/local/bin/restart_services.sh] birdnet_server.service is running'
+ break
+ systemctl is-active --quiet birdnet_server.service
+ grep 5050 /dev/fd/63
++ netstat -tulpn
tcp        0      0 127.0.0.1:5050          0.0.0.0:*               LISTEN      1516382/python3     
+ status=0
+ ((  status != 0  ))
+ sudo systemctl restart birdnet_analysis.service

I see log output from the "View Logs" page?

b0rnwr0ng commented 2 years ago

I see, sorry didn't have a great way to post this on the phone.

spectrogram_viewer.service - BirdNET-Pi Spectrogram Viewer Loaded: loaded (/home/pi/BirdNET-Pi/templates/spectrogram_viewer.service; enabled; vendor preset: enabled) Active: activating (auto-restart) (Result: exit-code) since Fri 2022-07-08 12:55:04 EDT; 3s ago Process: 3510 ExecStart=/usr/local/bin/spectrogram.sh (code=exited, status=2) Main PID: 3510 (code=exited, status=2) CPU: 16ms`

`

b0rnwr0ng commented 2 years ago 
getty@tty1.service - Getty on tty1
     Loaded: loaded (/lib/systemd/system/getty@.service; enabled; vendor preset: enabled)
    Drop-In: /etc/systemd/system/getty@tty1.service.d
             └─autologin.conf, noclear.conf
     Active: active (running) since Fri 2022-07-08 12:39:11 EDT; 17min ago
       Docs: man:agetty(8)
             man:systemd-getty-generator(8)
             http://0pointer.de/blog/projects/serial-console.html
   Main PID: 783 (login)
      Tasks: 0 (limit: 4164)
        CPU: 27ms
     CGroup: /system.slice/system-getty.slice/getty@tty1.service
             ‣ 783 /bin/login -f

Jul 08 12:39:11 birdnetpi systemd[1]: Started Getty on tty1.
Jul 08 12:39:16 birdnetpi login[783]: pam_unix(login:session): session opened for user pi(uid=1000) by LOGIN(uid=0)
-- Journal begins at Fri 2022-07-01 23:31:04 EDT, ends at Fri 2022-07-08 12:57:39 EDT. --
Jul 07 11:24:45 pulseaudio[817]: We were woken up with POLLIN set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail.
Jul 07 11:35:57 systemd[800]: Stopping Sound Service...
Jul 07 11:35:57 systemd[800]: pulseaudio.service: Succeeded.
Jul 07 11:35:57 systemd[800]: Stopped Sound Service.
Jul 07 11:35:57 systemd[800]: pulseaudio.service: Consumed 10.332s CPU time.
-- Boot a3f941c4895a4c16a81d8bad0c035256 --
Jul 07 11:36:39 systemd[804]: Starting Sound Service...
Jul 07 11:36:39 pulseaudio[821]: Failed to find a working profile.
Jul 07 11:36:39 pulseaudio[821]: Failed to load module "module-alsa-card" (argument: "device_id="1" name="platform-fef00700.hdmi" card_name="alsa_card.platform-fef00700.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Jul 07 11:36:39 pulseaudio[821]: Failed to find a working profile.
Jul 07 11:36:39 pulseaudio[821]: Failed to load module "module-alsa-card" (argument: "device_id="2" name="platform-fef05700.hdmi" card_name="alsa_card.platform-fef05700.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Jul 07 11:36:39 systemd[804]: Started Sound Service.
Jul 07 11:36:50 pulseaudio[821]: ALSA woke us up to read new data from the device, but there was actually nothing to read.
Jul 07 11:36:50 pulseaudio[821]: Most likely this is a bug in the ALSA driver 'snd_usb_audio'. Please report this issue to the ALSA developers.
Jul 07 11:36:50 pulseaudio[821]: We were woken up with POLLIN set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail.
Jul 07 12:52:30 systemd[804]: pulseaudio.service: Succeeded.
Jul 07 12:52:30 systemd[804]: pulseaudio.service: Consumed 53.249s CPU time.
-- Boot e3931e79b03c4ab49b3198b010bbceb0 --
Jul 07 12:53:11 systemd[793]: Starting Sound Service...
Jul 07 12:53:12 pulseaudio[810]: Failed to find a working profile.
Jul 07 12:53:12 pulseaudio[810]: Failed to load module "module-alsa-card" (argument: "device_id="1" name="platform-fef00700.hdmi" card_name="alsa_card.platform-fef00700.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Jul 07 12:53:12 pulseaudio[810]: Failed to find a working profile.
Jul 07 12:53:12 pulseaudio[810]: Failed to load module "module-alsa-card" (argument: "device_id="2" name="platform-fef05700.hdmi" card_name="alsa_card.platform-fef05700.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Jul 07 12:53:12 systemd[793]: Started Sound Service.
Jul 07 12:54:01 pulseaudio[810]: ALSA woke us up to read new data from the device, but there was actually nothing to read.
Jul 07 12:54:01 pulseaudio[810]: Most likely this is a bug in the ALSA driver 'snd_usb_audio'. Please report this issue to the ALSA developers.
Jul 07 12:54:01 pulseaudio[810]: We were woken up with POLLIN set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail.
Jul 07 16:06:04 pulseaudio[810]: Failed to find a working profile.
Jul 07 16:06:04 pulseaudio[810]: Failed to load module "module-alsa-card" (argument: "device_id="1" name="platform-fef00700.hdmi" card_name="alsa_card.platform-fef00700.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Jul 07 16:06:04 pulseaudio[810]: Failed to find a working profile.
Jul 07 16:06:04 pulseaudio[810]: Failed to load module "module-alsa-card" (argument: "device_id="2" name="platform-fef05700.hdmi" card_name="alsa_card.platform-fef05700.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Jul 07 16:06:18 systemd[793]: pulseaudio.service: Succeeded.
Jul 07 16:06:18 systemd[793]: Stopped Sound Service.
Jul 07 16:06:18 systemd[793]: pulseaudio.service: Consumed 2min 5.138s CPU time.
-- Boot daeb38f4f6f04420ba05fc5884fc9a29 --
Jul 07 16:07:09 systemd[806]: Starting Sound Service...
Jul 07 16:07:09 pulseaudio[821]: Failed to find a working profile.
Jul 07 16:07:09 pulseaudio[821]: Failed to load module "module-alsa-card" (argument: "device_id="1" name="platform-fef00700.hdmi" card_name="alsa_card.platform-fef00700.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Jul 07 16:07:09 pulseaudio[821]: Failed to find a working profile.
Jul 07 16:07:09 pulseaudio[821]: Failed to load module "module-alsa-card" (argument: "device_id="2" name="platform-fef05700.hdmi" card_name="alsa_card.platform-fef05700.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Jul 07 16:07:09 systemd[806]: Started Sound Service.
Jul 07 16:09:43 pulseaudio[821]: ALSA woke us up to read new data from the device, but there was actually nothing to read.
Jul 07 16:09:43 pulseaudio[821]: Most likely this is a bug in the ALSA driver 'snd_usb_audio'. Please report this issue to the ALSA developers.
Jul 07 16:09:43 pulseaudio[821]: We were woken up with POLLIN set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail.
-- Boot c84fcfddd1f74037a9960b2dba160840 --
Jul 08 12:39:16 systemd[844]: Starting Sound Service...
Jul 08 12:39:17 pulseaudio[860]: Failed to find a working profile.
Jul 08 12:39:17 pulseaudio[860]: Failed to load module "module-alsa-card" (argument: "device_id="1" name="platform-fef00700.hdmi" card_name="alsa_card.platform-fef00700.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Jul 08 12:39:17 pulseaudio[860]: Failed to find a working profile.
Jul 08 12:39:17 pulseaudio[860]: Failed to load module "module-alsa-card" (argument: "device_id="2" name="platform-fef05700.hdmi" card_name="alsa_card.platform-fef05700.hdmi" namereg_fail=false tsched=no fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Jul 08 12:39:17 systemd[844]: Started Sound Service.
Jul 08 12:41:40 pulseaudio[860]: ALSA woke us up to read new data from the device, but there was actually nothing to read.
Jul 08 12:41:40 pulseaudio[860]: Most likely this is a bug in the ALSA driver 'snd_usb_audio'. Please report this issue to the ALSA developers.
Jul 08 12:41:40 pulseaudio[860]: We were woken up with POLLIN set -- however a subsequent snd_pcm_avail() returned 0 or another value < min_avail.
mcguirepr89 commented 2 years ago

Hmm that also looks normal.

At this point, I have no idea what is broken. To me, it appears that everything is working, but that the audio files are empty. Can you test the microphone on another device to see that it is working properly?

If it is working on another device, let's try rebooting so that I can only view the log information from a clean boot:

  1. sudo reboot
  2. After the reboot, wait for the system to exhibit the issue, then we will collect the logs
  3. journalctl --no-hostname --no-pager --boot -ubirdnet_analysis -ubirdnet_server -ubirdnet_recording -uextraction | sed "s|$HOME||g;/LATITUDE=/d;/LONGITUDE=/d;/BIRDWEATHER/d;/PWD/d;/URL/d;/KEY/d;/STREAM/d"

    That command should scrub your personal info, but please double-check that there is no sensitive information within those logs --

b0rnwr0ng commented 2 years ago

mic works on my laptop, swapped with a different mic, same thing...should I just reimage at this point?

b0rnwr0ng commented 2 years ago

` -- Journal begins at Fri 2022-07-01 23:31:05 EDT, ends at Fri 2022-07-08 14:19:00 EDT. -- Jul 08 14:18:03 systemd[1]: Started BirdNET Recording. Jul 08 14:18:03 systemd[1]: Started BirdNET Analysis Server. Jul 08 14:18:03 systemd[1]: Started BirdNET Analysis. Jul 08 14:18:03 systemd[1]: Started BirdNET BirdSound Extraction. Jul 08 14:18:03 birdnet_recording.sh[400]: + source /etc/birdnet/birdnet.conf Jul 08 14:18:05 birdnet_recording.sh[400]: ++ PUSHED_APP_SECRET= Jul 08 14:18:05 birdnet_recording.sh[400]: ++ RECS_DIR=/BirdSongs Jul 08 14:18:05 birdnet_recording.sh[400]: ++ REC_CARD=default Jul 08 14:18:05 birdnet_recording.sh[400]: ++ PROCESSED=/BirdSongs/Processed Jul 08 14:18:05 birdnet_recording.sh[400]: ++ EXTRACTED=/BirdSongs/Extracted Jul 08 14:18:05 birdnet_recording.sh[400]: ++ OVERLAP=0.0 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ CONFIDENCE=0.65 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ SENSITIVITY=1.25 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ CHANNELS=2 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ FULL_DISK=purge Jul 08 14:18:05 birdnet_recording.sh[400]: ++ PRIVACY_MODE=on Jul 08 14:18:05 birdnet_recording.sh[400]: ++ RECORDING_LENGTH=15 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ EXTRACTION_LENGTH= Jul 08 14:18:05 birdnet_recording.sh[400]: ++ AUDIOFMT=mp3 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ LAST_RUN= Jul 08 14:18:05 birdnet_recording.sh[400]: ++ THIS_RUN= Jul 08 14:18:05 birdnet_recording.sh[400]: ++ IDFILE=/BirdNET-Pi/IdentifiedSoFar.txt Jul 08 14:18:05 birdnet_recording.sh[400]: ++ PRIVACY_THRESHOLD=3 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ APPRISE_NOTIFICATION_TITLE='New BirdNET-Pi Detection' Jul 08 14:18:05 birdnet_recording.sh[400]: ++ APPRISE_NOTIFICATION_BODY='A $sciname $comname was just detected with a confidence of $confidence' Jul 08 14:18:05 birdnet_recording.sh[400]: ++ APPRISE_NOTIFY_EACH_DETECTION=0 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ APPRISE_NOTIFY_NEW_SPECIES=1 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ DATABASE_LANG=en Jul 08 14:18:05 birdnet_recording.sh[400]: ++ FLICKR_FILTER_EMAIL= Jul 08 14:18:05 birdnet_recording.sh[400]: ++ APPRISE_NOTIFY_NEW_SPECIES_EACH_DAY=1 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ APPRISE_WEEKLY_REPORT=1 Jul 08 14:18:05 birdnet_recording.sh[400]: ++ SILENCE_UPDATE_INDICATOR=0 Jul 08 14:18:05 birdnet_recording.sh[400]: + '[' -z 15 ']' Jul 08 14:18:05 birdnet_recording.sh[400]: + '[' '!' -z ']' Jul 08 14:18:05 birdnet_recording.sh[400]: + pulseaudio --check Jul 08 14:18:05 birdnet_recording.sh[467]: E: [pulseaudio] core-util.c: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 14:18:05 birdnet_recording.sh[400]: + pulseaudio --start Jul 08 14:18:05 birdnet_recording.sh[544]: E: [autospawn] core-util.c: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 14:18:05 birdnet_recording.sh[544]: W: [autospawn] lock-autospawn.c: Cannot access autospawn lock. Jul 08 14:18:05 birdnet_recording.sh[544]: E: [pulseaudio] main.c: Failed to acquire autospawn lock Jul 08 14:18:05 birdnet_recording.sh[400]: + pgrep arecord Jul 08 14:18:05 birdnet_recording.sh[400]: + grep 5050 /dev/fd/63 Jul 08 14:18:05 birdnet_recording.sh[568]: ++ netstat -tulpn Jul 08 14:18:05 birdnet_recording.sh[400]: + sleep 1 Jul 08 14:18:05 systemd[1]: Stopping BirdNET Recording... Jul 08 14:18:05 systemd[1]: birdnet_recording.service: Succeeded. Jul 08 14:18:05 systemd[1]: Stopped BirdNET Recording. Jul 08 14:18:05 systemd[1]: Stopping BirdNET Analysis... Jul 08 14:18:05 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:18:05 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:18:05 systemd[1]: Stopping BirdNET Analysis Server... Jul 08 14:18:05 systemd[1]: birdnet_server.service: Succeeded. Jul 08 14:18:05 systemd[1]: Stopped BirdNET Analysis Server. Jul 08 14:18:05 systemd[1]: Stopping BirdNET BirdSound Extraction... Jul 08 14:18:06 env[434]: Terminated Jul 08 14:18:06 systemd[1]: extraction.service: Succeeded. Jul 08 14:18:06 systemd[1]: Stopped BirdNET BirdSound Extraction.

`

b0rnwr0ng commented 2 years ago

I deleted Thursdays folders as it seems I have wav files from today (Friday-08) at 12:23 and on.. I removed all the July folders, wondering if its hung up on that since the error still states for a Thursday file..

b0rnwr0ng commented 2 years ago

rebooted and that seems to work now..

b0rnwr0ng commented 2 years ago

`

-- Journal begins at Fri 2022-07-01 23:31:05 EDT, ends at Fri 2022-07-08 14:28:09 EDT. -- Jul 08 14:25:17 systemd[1]: Started BirdNET Recording. Jul 08 14:25:17 systemd[1]: Started BirdNET Analysis Server. Jul 08 14:25:17 systemd[1]: Started BirdNET Analysis. Jul 08 14:25:17 systemd[1]: Started BirdNET BirdSound Extraction. Jul 08 14:25:17 birdnet_recording.sh[393]: + source /etc/birdnet/birdnet.conf Jul 08 14:25:18 birdnet_recording.sh[393]: ++ PUSHED_APP_SECRET= Jul 08 14:25:18 birdnet_recording.sh[393]: ++ RECS_DIR=/BirdSongs Jul 08 14:25:18 birdnet_recording.sh[393]: ++ REC_CARD=default Jul 08 14:25:18 birdnet_recording.sh[393]: ++ PROCESSED=/BirdSongs/Processed Jul 08 14:25:18 birdnet_recording.sh[393]: ++ EXTRACTED=/BirdSongs/Extracted Jul 08 14:25:18 birdnet_recording.sh[393]: ++ OVERLAP=0.0 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ CONFIDENCE=0.65 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ SENSITIVITY=1.25 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ CHANNELS=2 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ FULL_DISK=purge Jul 08 14:25:18 birdnet_recording.sh[393]: ++ PRIVACY_MODE=on Jul 08 14:25:18 birdnet_recording.sh[393]: ++ RECORDING_LENGTH=15 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ EXTRACTION_LENGTH= Jul 08 14:25:18 birdnet_recording.sh[393]: ++ AUDIOFMT=mp3 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ LAST_RUN= Jul 08 14:25:18 birdnet_recording.sh[393]: ++ THIS_RUN= Jul 08 14:25:18 birdnet_recording.sh[393]: ++ IDFILE=/BirdNET-Pi/IdentifiedSoFar.txt Jul 08 14:25:18 birdnet_recording.sh[393]: ++ PRIVACY_THRESHOLD=3 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ APPRISE_NOTIFICATION_TITLE='New BirdNET-Pi Detection' Jul 08 14:25:18 birdnet_recording.sh[393]: ++ APPRISE_NOTIFICATION_BODY='A $sciname $comname was just detected with a confidence of $confidence' Jul 08 14:25:18 birdnet_recording.sh[393]: ++ APPRISE_NOTIFY_EACH_DETECTION=0 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ APPRISE_NOTIFY_NEW_SPECIES=1 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ DATABASE_LANG=en Jul 08 14:25:18 birdnet_recording.sh[393]: ++ FLICKR_FILTER_EMAIL= Jul 08 14:25:18 birdnet_recording.sh[393]: ++ APPRISE_NOTIFY_NEW_SPECIES_EACH_DAY=1 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ APPRISE_WEEKLY_REPORT=1 Jul 08 14:25:18 birdnet_recording.sh[393]: ++ SILENCE_UPDATE_INDICATOR=0 Jul 08 14:25:18 birdnet_recording.sh[393]: + '[' -z 15 ']' Jul 08 14:25:18 birdnet_recording.sh[393]: + '[' '!' -z ']' Jul 08 14:25:18 birdnet_recording.sh[393]: + pulseaudio --check Jul 08 14:25:19 birdnet_recording.sh[442]: E: [pulseaudio] core-util.c: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 14:25:19 birdnet_recording.sh[393]: + pulseaudio --start Jul 08 14:25:19 birdnet_recording.sh[528]: E: [autospawn] core-util.c: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 14:25:19 birdnet_recording.sh[528]: W: [autospawn] lock-autospawn.c: Cannot access autospawn lock. Jul 08 14:25:19 birdnet_recording.sh[528]: E: [pulseaudio] main.c: Failed to acquire autospawn lock Jul 08 14:25:19 birdnet_recording.sh[393]: + pgrep arecord Jul 08 14:25:19 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:19 birdnet_recording.sh[553]: ++ netstat -tulpn Jul 08 14:25:19 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:19 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:19 birdnet_recording.sh[623]: ++ netstat -tulpn Jul 08 14:25:19 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:20 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:20 birdnet_recording.sh[642]: ++ netstat -tulpn Jul 08 14:25:20 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:21 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:21 birdnet_recording.sh[653]: ++ netstat -tulpn Jul 08 14:25:21 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:22 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:22 birdnet_recording.sh[665]: ++ netstat -tulpn Jul 08 14:25:22 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:23 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:23 birdnet_recording.sh[677]: ++ netstat -tulpn Jul 08 14:25:23 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:24 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:24 birdnet_recording.sh[685]: ++ netstat -tulpn Jul 08 14:25:24 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:25 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:25 birdnet_recording.sh[740]: ++ netstat -tulpn Jul 08 14:25:25 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:26 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:26 birdnet_recording.sh[748]: ++ netstat -tulpn Jul 08 14:25:26 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:27 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:27 birdnet_recording.sh[757]: ++ netstat -tulpn Jul 08 14:25:27 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:28 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:28 birdnet_recording.sh[775]: ++ netstat -tulpn Jul 08 14:25:28 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:29 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:29 birdnet_recording.sh[787]: ++ netstat -tulpn Jul 08 14:25:29 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:30 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:30 birdnet_recording.sh[796]: ++ netstat -tulpn Jul 08 14:25:30 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:31 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:31 birdnet_recording.sh[811]: ++ netstat -tulpn Jul 08 14:25:31 birdnet_recording.sh[393]: + sleep 1 Jul 08 14:25:31 python3[394]: INFO: Created TensorFlow Lite XNNPACK delegate for CPU. Jul 08 14:25:31 python3[394]: INFO: Created TensorFlow Lite delegate for select TF ops. Jul 08 14:25:31 python3[394]: INFO: TfLiteFlexDelegate delegate: 1 nodes delegated out of 182 nodes with 1 partitions. Jul 08 14:25:32 birdnet_recording.sh[393]: + grep 5050 /dev/fd/63 Jul 08 14:25:32 birdnet_recording.sh[827]: ++ netstat -tulpn Jul 08 14:25:32 birdnet_recording.sh[828]: tcp 0 0 127.0.0.1:5050 0.0.0.0: LISTEN 394/python3 Jul 08 14:25:32 birdnet_recording.sh[393]: + '[' -z default ']' Jul 08 14:25:32 birdnet_recording.sh[393]: + arecord -f S16_LE -c2 -r48000 -t wav --max-file-time 15 -D default --use-strftime /BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav Jul 08 14:25:32 birdnet_recording.sh[836]: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 14:25:32 birdnet_recording.sh[836]: arecord: main:830: audio open error: No such file or directory Jul 08 14:25:32 systemd[1]: birdnet_recording.service: Main process exited, code=exited, status=1/FAILURE Jul 08 14:25:32 systemd[1]: birdnet_recording.service: Failed with result 'exit-code'. Jul 08 14:25:32 birdnet_analysis.sh[888]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:25:32 birdnet_analysis.sh[896]: find: ‘/BirdSongs/July-2022/08-Friday’: No such file or directory Jul 08 14:25:33 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:25:52 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 1. Jul 08 14:25:52 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:25:52 systemd[1]: Started BirdNET Analysis. Jul 08 14:25:52 systemd[1]: birdnet_recording.service: Scheduled restart job, restart counter is at 1. Jul 08 14:25:52 systemd[1]: Stopped BirdNET Recording. Jul 08 14:25:52 systemd[1]: Started BirdNET Recording. Jul 08 14:25:52 birdnet_recording.sh[939]: + source /etc/birdnet/birdnet.conf Jul 08 14:25:52 birdnet_recording.sh[939]: ++ PUSHED_APP_SECRET= Jul 08 14:25:52 birdnet_recording.sh[939]: ++ RECS_DIR=/BirdSongs Jul 08 14:25:52 birdnet_recording.sh[939]: ++ REC_CARD=default Jul 08 14:25:52 birdnet_recording.sh[939]: ++ PROCESSED=/BirdSongs/Processed Jul 08 14:25:52 birdnet_recording.sh[939]: ++ EXTRACTED=/BirdSongs/Extracted Jul 08 14:25:52 birdnet_recording.sh[939]: ++ OVERLAP=0.0 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ CONFIDENCE=0.65 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ SENSITIVITY=1.25 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ CHANNELS=2 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ FULL_DISK=purge Jul 08 14:25:52 birdnet_recording.sh[939]: ++ PRIVACY_MODE=on Jul 08 14:25:52 birdnet_recording.sh[939]: ++ RECORDING_LENGTH=15 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ EXTRACTION_LENGTH= Jul 08 14:25:52 birdnet_recording.sh[939]: ++ AUDIOFMT=mp3 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ LAST_RUN= Jul 08 14:25:52 birdnet_recording.sh[939]: ++ THIS_RUN= Jul 08 14:25:52 birdnet_recording.sh[939]: ++ IDFILE=/BirdNET-Pi/IdentifiedSoFar.txt Jul 08 14:25:52 birdnet_recording.sh[939]: ++ PRIVACY_THRESHOLD=3 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ APPRISE_NOTIFICATION_TITLE='New BirdNET-Pi Detection' Jul 08 14:25:52 birdnet_recording.sh[939]: ++ APPRISE_NOTIFICATION_BODY='A $sciname $comname was just detected with a confidence of $confidence' Jul 08 14:25:52 birdnet_recording.sh[939]: ++ APPRISE_NOTIFY_EACH_DETECTION=0 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ APPRISE_NOTIFY_NEW_SPECIES=1 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ DATABASE_LANG=en Jul 08 14:25:52 birdnet_recording.sh[939]: ++ FLICKR_FILTER_EMAIL= Jul 08 14:25:52 birdnet_recording.sh[939]: ++ APPRISE_NOTIFY_NEW_SPECIES_EACH_DAY=1 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ APPRISE_WEEKLY_REPORT=1 Jul 08 14:25:52 birdnet_recording.sh[939]: ++ SILENCE_UPDATE_INDICATOR=0 Jul 08 14:25:52 birdnet_recording.sh[939]: + '[' -z 15 ']' Jul 08 14:25:52 birdnet_recording.sh[939]: + '[' '!' -z ']' Jul 08 14:25:52 birdnet_recording.sh[939]: + pulseaudio --check Jul 08 14:25:52 birdnet_recording.sh[940]: E: [pulseaudio] core-util.c: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 14:25:52 birdnet_recording.sh[939]: + pulseaudio --start Jul 08 14:25:52 birdnet_recording.sh[941]: E: [autospawn] core-util.c: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 14:25:52 birdnet_recording.sh[941]: W: [autospawn] lock-autospawn.c: Cannot access autospawn lock. Jul 08 14:25:52 birdnet_recording.sh[941]: E: [pulseaudio] main.c: Failed to acquire autospawn lock Jul 08 14:25:52 birdnet_recording.sh[939]: + pgrep arecord Jul 08 14:25:52 birdnet_recording.sh[939]: + grep 5050 /dev/fd/63 Jul 08 14:25:52 birdnet_recording.sh[944]: ++ netstat -tulpn Jul 08 14:25:52 birdnet_recording.sh[945]: tcp 0 0 127.0.0.1:5050 0.0.0.0: LISTEN 394/python3 Jul 08 14:25:52 birdnet_recording.sh[939]: + '[' -z default ']' Jul 08 14:25:52 birdnet_recording.sh[939]: + arecord -f S16_LE -c2 -r48000 -t wav --max-file-time 15 -D default --use-strftime /BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav Jul 08 14:25:52 birdnet_recording.sh[951]: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 14:25:52 birdnet_analysis.sh[959]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:25:52 birdnet_recording.sh[951]: arecord: main:830: audio open error: No such file or directory Jul 08 14:25:52 systemd[1]: birdnet_recording.service: Main process exited, code=exited, status=1/FAILURE Jul 08 14:25:52 systemd[1]: birdnet_recording.service: Failed with result 'exit-code'. Jul 08 14:25:53 birdnet_analysis.sh[967]: find: ‘/BirdSongs/July-2022/08-Friday’: No such file or directory Jul 08 14:25:53 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:25:55 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 2. Jul 08 14:25:55 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:25:55 systemd[1]: Started BirdNET Analysis. Jul 08 14:25:56 birdnet_analysis.sh[1043]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:25:56 birdnet_analysis.sh[1051]: find: ‘/BirdSongs/July-2022/08-Friday’: No such file or directory Jul 08 14:25:56 systemd[1]: birdnet_recording.service: Scheduled restart job, restart counter is at 2. Jul 08 14:25:56 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:25:56 systemd[1]: Stopped BirdNET Recording. Jul 08 14:25:56 systemd[1]: Started BirdNET Recording. Jul 08 14:25:56 birdnet_recording.sh[1053]: + source /etc/birdnet/birdnet.conf Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ PUSHED_APP_SECRET= Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ RECS_DIR=/BirdSongs Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ REC_CARD=default Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ PROCESSED=/BirdSongs/Processed Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ EXTRACTED=/BirdSongs/Extracted Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ OVERLAP=0.0 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ CONFIDENCE=0.65 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ SENSITIVITY=1.25 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ CHANNELS=2 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ FULL_DISK=purge Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ PRIVACY_MODE=on Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ RECORDING_LENGTH=15 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ EXTRACTION_LENGTH= Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ AUDIOFMT=mp3 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ LAST_RUN= Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ THIS_RUN= Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ IDFILE=/BirdNET-Pi/IdentifiedSoFar.txt Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ PRIVACY_THRESHOLD=3 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ APPRISE_NOTIFICATION_TITLE='New BirdNET-Pi Detection' Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ APPRISE_NOTIFICATION_BODY='A $sciname $comname was just detected with a confidence of $confidence' Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ APPRISE_NOTIFY_EACH_DETECTION=0 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ APPRISE_NOTIFY_NEW_SPECIES=1 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ DATABASE_LANG=en Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ FLICKR_FILTER_EMAIL= Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ APPRISE_NOTIFY_NEW_SPECIES_EACH_DAY=1 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ APPRISE_WEEKLY_REPORT=1 Jul 08 14:25:56 birdnet_recording.sh[1053]: ++ SILENCE_UPDATE_INDICATOR=0 Jul 08 14:25:56 birdnet_recording.sh[1053]: + '[' -z 15 ']' Jul 08 14:25:56 birdnet_recording.sh[1053]: + '[' '!' -z ']' Jul 08 14:25:56 birdnet_recording.sh[1053]: + pulseaudio --check Jul 08 14:25:56 birdnet_recording.sh[1053]: + pgrep arecord Jul 08 14:25:56 birdnet_recording.sh[1053]: + grep 5050 /dev/fd/63 Jul 08 14:25:56 birdnet_recording.sh[1056]: ++ netstat -tulpn Jul 08 14:25:56 birdnet_recording.sh[1057]: tcp 0 0 127.0.0.1:5050 0.0.0.0:* LISTEN 394/python3 Jul 08 14:25:56 birdnet_recording.sh[1053]: + '[' -z default ']' Jul 08 14:25:56 birdnet_recording.sh[1053]: + arecord -f S16_LE -c2 -r48000 -t wav --max-file-time 15 -D default --use-strftime /BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav Jul 08 14:25:56 birdnet_recording.sh[1059]: Recording WAVE '/BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav' : Signed 16 bit Little Endian, Rate 48000 Hz, Stereo Jul 08 14:25:58 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 3. Jul 08 14:25:58 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:25:58 systemd[1]: Started BirdNET Analysis. Jul 08 14:25:59 birdnet_analysis.sh[1086]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:25:59 birdnet_analysis.sh[1068]: RECORDING_LENGTH set to 15 Jul 08 14:26:12 birdnet_analysis.sh[1068]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:25:56.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:25:56.wav.csv --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 14:26:12 python3[394]: LOADING TF LITE MODEL... DONE! Jul 08 14:26:12 python3[394]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 14:26:12 python3[394]: Date-time: 2022-07-08 14:25:56 Jul 08 14:26:15 birdnet_analysis.sh[1183]: 0.0;3.0-('Scolopax minor_American Woodcock', 0.045819346) Jul 08 14:26:15 birdnet_analysis.sh[1183]: 3.0;6.0-('Rallus crepitans_Clapper Rail', 0.0541643) Jul 08 14:26:15 birdnet_analysis.sh[1183]: 6.0;9.0-('Scolopax minor_American Woodcock', 0.039338) Jul 08 14:26:15 birdnet_analysis.sh[1183]: 9.0;12.0-('Scolopax minor_American Woodcock', 0.06096222) Jul 08 14:26:15 birdnet_analysis.sh[1183]: 12.0;15.0-('Scolopax minor_American Woodcock', 0.03815245) Jul 08 14:26:15 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:26:17 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 4. Jul 08 14:26:17 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:26:17 systemd[1]: Started BirdNET Analysis. Jul 08 14:26:18 birdnet_analysis.sh[1229]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:26:18 birdnet_analysis.sh[1211]: Files loaded Jul 08 14:26:18 birdnet_analysis.sh[1211]: 'Analyzed' directory created Jul 08 14:26:19 birdnet_analysis.sh[1211]: RECORDING_LENGTH set to 15 Jul 08 14:26:19 env[1258]: Line = 2022-07-08-birdnet-14:25:56.wav.csv Jul 08 14:26:28 birdnet_analysis.sh[1211]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:11.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:11.wav.csv --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 14:26:28 python3[394]: ANALYZING AUDIO... DONE! Time 2.9 SECONDS Jul 08 14:26:28 python3[394]: WRITING RESULTS TO /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:25:56.wav.csv ... DONE! WROTE 0 RESULTS. Jul 08 14:26:28 python3[394]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 14:26:28 python3[394]: Date-time: 2022-07-08 14:26:11 Jul 08 14:26:29 birdnet_analysis.sh[1344]: 0.0;3.0-('Rallus crepitans_Clapper Rail', 0.044552796) Jul 08 14:26:29 birdnet_analysis.sh[1344]: 3.0;6.0-('Scolopax minor_American Woodcock', 0.044252496) Jul 08 14:26:29 birdnet_analysis.sh[1344]: 6.0;9.0-('Scolopax minor_American Woodcock', 0.039268177) Jul 08 14:26:29 birdnet_analysis.sh[1344]: 9.0;12.0-('Scolopax minor_American Woodcock', 0.047316194) Jul 08 14:26:29 birdnet_analysis.sh[1344]: 12.0;15.0-('Spizella pusilla_Field Sparrow', 0.03755045) Jul 08 14:26:29 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:26:32 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 5. Jul 08 14:26:32 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:26:32 systemd[1]: Started BirdNET Analysis. Jul 08 14:26:33 birdnet_analysis.sh[1412]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:26:33 birdnet_analysis.sh[1380]: Files loaded Jul 08 14:26:33 birdnet_analysis.sh[1380]: RECORDING_LENGTH set to 15 Jul 08 14:26:35 env[1444]: Line = 2022-07-08-birdnet-14:26:11.wav.csv Jul 08 14:26:42 birdnet_analysis.sh[1380]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:26.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:26.wav.csv --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 14:26:42 python3[394]: ANALYZING AUDIO... DONE! Time 1.7 SECONDS Jul 08 14:26:42 python3[394]: WRITING RESULTS TO /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:11.wav.csv ... DONE! WROTE 0 RESULTS. Jul 08 14:26:43 python3[394]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 14:26:43 python3[394]: Date-time: 2022-07-08 14:26:26 Jul 08 14:26:43 birdnet_analysis.sh[1524]: 0.0;3.0-('Scolopax minor_American Woodcock', 0.039143432) Jul 08 14:26:43 birdnet_analysis.sh[1524]: 3.0;6.0-('Scolopax minor_American Woodcock', 0.039371673) Jul 08 14:26:43 birdnet_analysis.sh[1524]: 6.0;9.0-('Euphagus carolinus_Rusty Blackbird', 0.06989078) Jul 08 14:26:43 birdnet_analysis.sh[1524]: 9.0;12.0-('Scolopax minor_American Woodcock', 0.043912634) Jul 08 14:26:43 birdnet_analysis.sh[1524]: 12.0;15.0-('Scolopax minor_American Woodcock', 0.04549015) Jul 08 14:26:43 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:26:45 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 6. Jul 08 14:26:45 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:26:45 systemd[1]: Started BirdNET Analysis. Jul 08 14:26:46 birdnet_analysis.sh[1564]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:26:46 birdnet_analysis.sh[1543]: Files loaded Jul 08 14:26:47 birdnet_analysis.sh[1543]: RECORDING_LENGTH set to 15 Jul 08 14:26:48 env[1593]: Line = 2022-07-08-birdnet-14:26:26.wav.csv Jul 08 14:26:57 birdnet_analysis.sh[1543]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:41.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:41.wav.csv --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 14:26:58 python3[394]: ANALYZING AUDIO... DONE! Time 1.5 SECONDS Jul 08 14:26:58 python3[394]: WRITING RESULTS TO /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:26.wav.csv ... DONE! WROTE 0 RESULTS. Jul 08 14:26:58 python3[394]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 14:26:58 python3[394]: Date-time: 2022-07-08 14:26:41 Jul 08 14:26:59 birdnet_analysis.sh[1693]: 0.0;3.0-('Scolopax minor_American Woodcock', 0.04647716) Jul 08 14:26:59 birdnet_analysis.sh[1693]: 3.0;6.0-('Scolopax minor_American Woodcock', 0.047612887) Jul 08 14:26:59 birdnet_analysis.sh[1693]: 6.0;9.0-('Scolopax minor_American Woodcock', 0.045921575) Jul 08 14:26:59 birdnet_analysis.sh[1693]: 9.0;12.0-('Scolopax minor_American Woodcock', 0.04412315) Jul 08 14:26:59 birdnet_analysis.sh[1693]: 12.0;15.0-('Scolopax minor_American Woodcock', 0.04448633) Jul 08 14:26:59 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:27:01 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 7. Jul 08 14:27:01 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:27:01 systemd[1]: Started BirdNET Analysis. Jul 08 14:27:02 birdnet_analysis.sh[1772]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:27:02 birdnet_analysis.sh[1752]: Files loaded Jul 08 14:27:03 birdnet_analysis.sh[1752]: RECORDING_LENGTH set to 15 Jul 08 14:27:03 env[1804]: Line = 2022-07-08-birdnet-14:26:41.wav.csv Jul 08 14:27:11 birdnet_analysis.sh[1752]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:56.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:56.wav.csv --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 14:27:12 python3[394]: ANALYZING AUDIO... DONE! Time 1.6 SECONDS Jul 08 14:27:12 python3[394]: WRITING RESULTS TO /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:41.wav.csv ... DONE! WROTE 0 RESULTS. Jul 08 14:27:12 python3[394]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 14:27:12 python3[394]: Date-time: 2022-07-08 14:26:56 Jul 08 14:27:13 birdnet_analysis.sh[1884]: 0.0;3.0-('Scolopax minor_American Woodcock', 0.047357287) Jul 08 14:27:13 birdnet_analysis.sh[1884]: 3.0;6.0-('Scolopax minor_American Woodcock', 0.051338892) Jul 08 14:27:13 birdnet_analysis.sh[1884]: 6.0;9.0-('Human_Human', 0.0) Jul 08 14:27:13 birdnet_analysis.sh[1884]: 9.0;12.0-('Scolopax minor_American Woodcock', 0.046156496) Jul 08 14:27:13 birdnet_analysis.sh[1884]: 12.0;15.0-('Scolopax minor_American Woodcock', 0.03814964) Jul 08 14:27:13 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:27:15 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 8. Jul 08 14:27:15 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:27:15 systemd[1]: Started BirdNET Analysis. Jul 08 14:27:16 birdnet_analysis.sh[1935]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:27:16 birdnet_analysis.sh[1903]: Files loaded Jul 08 14:27:17 birdnet_analysis.sh[1903]: RECORDING_LENGTH set to 15 Jul 08 14:27:19 env[1972]: Line = 2022-07-08-birdnet-14:26:56.wav.csv Jul 08 14:27:27 birdnet_analysis.sh[1903]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:27:11.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:27:11.wa --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 14:27:28 python3[394]: ANALYZING AUDIO... DONE! Time 1.6 SECONDS Jul 08 14:27:28 python3[394]: WRITING RESULTS TO /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:26:56.wav.csv ... DONE! WROTE 0 RESULTS. Jul 08 14:27:28 python3[394]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 14:27:28 python3[394]: Date-time: 2022-07-08 14:27:11 Jul 08 14:27:29 birdnet_analysis.sh[2058]: 0.0;3.0-('Human_Human', 0.0) Jul 08 14:27:29 birdnet_analysis.sh[2058]: 3.0;6.0-('Human_Human', 0.0) Jul 08 14:27:29 birdnet_analysis.sh[2058]: 6.0;9.0-('Strix varia_Barred Owl', 0.04436459) Jul 08 14:27:29 birdnet_analysis.sh[2058]: 9.0;12.0-('Rallus crepitans_Clapper Rail', 0.048217207) Jul 08 14:27:29 birdnet_analysis.sh[2058]: 12.0;15.0-('Scolopax minor_American Woodcock', 0.041105594) Jul 08 14:27:29 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:27:31 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 9. Jul 08 14:27:31 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:27:31 systemd[1]: Started BirdNET Analysis. Jul 08 14:27:32 birdnet_analysis.sh[2113]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:27:32 birdnet_analysis.sh[2079]: Files loaded Jul 08 14:27:33 birdnet_analysis.sh[2079]: RECORDING_LENGTH set to 15 Jul 08 14:27:35 env[2145]: Line = 2022-07-08-birdnet-14:27:11.wav.csv Jul 08 14:27:42 birdnet_analysis.sh[2079]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:27:26.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:27:26.wav.csv --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 14:27:42 python3[394]: ANALYZING AUDIO... DONE! Time 1.5 SECONDS Jul 08 14:27:42 python3[394]: WRITING RESULTS TO /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:27:11.wav.csv ... DONE! WROTE 0 RESULTS. Jul 08 14:27:42 python3[394]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 14:27:42 python3[394]: Date-time: 2022-07-08 14:27:26 Jul 08 14:27:43 birdnet_analysis.sh[2249]: 0.0;3.0-('Scolopax minor_American Woodcock', 0.044857007) Jul 08 14:27:43 birdnet_analysis.sh[2249]: 3.0;6.0-('Human_Human', 0.0) Jul 08 14:27:43 birdnet_analysis.sh[2249]: 6.0;9.0-('Human_Human', 0.0) Jul 08 14:27:43 birdnet_analysis.sh[2249]: 9.0;12.0-('Human_Human', 0.0) Jul 08 14:27:43 birdnet_analysis.sh[2249]: 12.0;15.0-('Scolopax minor_American Woodcock', 0.05038723) Jul 08 14:27:43 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:27:45 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 10. Jul 08 14:27:45 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:27:45 systemd[1]: Started BirdNET Analysis. Jul 08 14:27:46 birdnet_analysis.sh[2294]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:27:46 birdnet_analysis.sh[2276]: Files loaded Jul 08 14:27:47 birdnet_analysis.sh[2276]: RECORDING_LENGTH set to 15 Jul 08 14:27:48 env[2323]: Line = 2022-07-08-birdnet-14:27:26.wav.csv Jul 08 14:27:57 birdnet_analysis.sh[2276]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:27:41.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:27:41.wav.csv --lat 40.XX --lon -74.XX --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 14:27:58 python3[394]: ANALYZING AUDIO... DONE! Time 1.5 SECONDS Jul 08 14:27:58 python3[394]: WRITING RESULTS TO /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-14:27:26.wav.csv ... DONE! WROTE 0 RESULTS. Jul 08 14:27:58 python3[394]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 14:27:58 python3[394]: Date-time: 2022-07-08 14:27:41 Jul 08 14:27:59 birdnet_analysis.sh[2443]: 0.0;3.0-('Human_Human', 0.0) Jul 08 14:27:59 birdnet_analysis.sh[2443]: 3.0;6.0-('Human_Human', 0.0) Jul 08 14:27:59 birdnet_analysis.sh[2443]: 6.0;9.0-('Human_Human', 0.0) Jul 08 14:27:59 birdnet_analysis.sh[2443]: 9.0;12.0-('Scolopax minor_American Woodcock', 0.049558356) Jul 08 14:27:59 birdnet_analysis.sh[2443]: 12.0;15.0-('Scolopax minor_American Woodcock', 0.04480068) Jul 08 14:27:59 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 14:28:01 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 11. Jul 08 14:28:01 systemd[1]: Stopped BirdNET Analysis. Jul 08 14:28:01 systemd[1]: Started BirdNET Analysis. Jul 08 14:28:02 birdnet_analysis.sh[2494]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 14:28:02 birdnet_analysis.sh[2473]: Files loaded Jul 08 14:28:03 birdnet_analysis.sh[2473]: RECORDING_LENGTH set to 15 Jul 08 14:28:03 env[2523]: Line = 2022-07-08-birdnet-14:27:41.wav.csv

`

b0rnwr0ng commented 2 years ago

I must've rebooted after I made the discord notification changes and did apt get upgrade, and I'm assuming the wav file got corrupted during the reboot and stuck there for processing.

b0rnwr0ng commented 2 years ago

Eh, things are a bit wacky still. Live audio not working. Lot of noise in my area tofay so figured thats why it wasn't picking anything up

mcguirepr89 commented 2 years ago

I'm afraid I will need a lot more information than its wackyness :)

journalctl --no-hostname --no-pager -n300 -ubirdnet_analysis -ubirdnet_server -ubirdnet_recording -uextraction | sed "s|$HOME||g;/LATITUDE=/d;/LONGITUDE=/d;/BIRDWEATHER/d;/PWD/d;/URL/d;/KEY/d;/STREAM/d"
b0rnwr0ng commented 2 years ago

Lol, yeah sorry lots going on atm. Seems normal right now, but nothing showing, but there is a lot of work going on in my area which may have something to do with it. Saw errors earlier but not so much now.. May need to wait until tomorrow

b0rnwr0ng commented 2 years ago

Created streamdata folder: Jul 08 18:06:35 systemd[1]: Stopped BirdNET Analysis. Jul 08 18:06:35 systemd[1]: Started BirdNET Analysis. Jul 08 18:06:36 birdnet_analysis.sh[124853]: find: ‘/BirdSongs/StreamData’: No such file or directory Jul 08 18:06:36 birdnet_analysis.sh[124835]: Files loaded Jul 08 18:06:37 birdnet_analysis.sh[124835]: RECORDING_LENGTH set to 15 Jul 08 18:06:38 env[124889]: Line = 2022-07-08-birdnet-18:06:16.wav.csv Jul 08 18:06:47 birdnet_analysis.sh[124835]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:06:31.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:06:31.wav.csv

b0rnwr0ng commented 2 years ago

This appear to show resulta not written: `

-- Boot 897bc0f7d6854cbc8da81ff2a294a998 -- Jul 08 18:10:25 systemd[1]: Started BirdNET Recording. Jul 08 18:10:25 systemd[1]: Started BirdNET Analysis Server. Jul 08 18:10:25 systemd[1]: Started BirdNET Analysis. Jul 08 18:10:25 systemd[1]: Started BirdNET BirdSound Extraction. Jul 08 18:10:25 birdnet_recording.sh[395]: + source /etc/birdnet/birdnet.conf Jul 08 18:10:27 birdnet_recording.sh[395]: ++ PUSHED_APP_SECRET= Jul 08 18:10:27 birdnet_recording.sh[395]: ++ RECS_DIR=/BirdSongs Jul 08 18:10:27 birdnet_recording.sh[395]: ++ REC_CARD=default Jul 08 18:10:27 birdnet_recording.sh[395]: ++ PROCESSED=/BirdSongs/Processed Jul 08 18:10:27 birdnet_recording.sh[395]: ++ EXTRACTED=/BirdSongs/Extracted Jul 08 18:10:27 birdnet_recording.sh[395]: ++ OVERLAP=0.0 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ CONFIDENCE=0.65 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ SENSITIVITY=1.25 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ CHANNELS=2 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ FULL_DISK=purge Jul 08 18:10:27 birdnet_recording.sh[395]: ++ PRIVACY_MODE=on Jul 08 18:10:27 birdnet_recording.sh[395]: ++ RECORDING_LENGTH=15 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ EXTRACTION_LENGTH= Jul 08 18:10:27 birdnet_recording.sh[395]: ++ AUDIOFMT=mp3 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ LAST_RUN= Jul 08 18:10:27 birdnet_recording.sh[395]: ++ THIS_RUN= Jul 08 18:10:27 birdnet_recording.sh[395]: ++ IDFILE=/BirdNET-Pi/IdentifiedSoFar.txt Jul 08 18:10:27 birdnet_recording.sh[395]: ++ PRIVACY_THRESHOLD=3 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ APPRISE_NOTIFICATION_TITLE='New BirdNET-Pi Detection' Jul 08 18:10:27 birdnet_recording.sh[395]: ++ APPRISE_NOTIFICATION_BODY='A $sciname $comname was just detected with a confidence of $confidence' Jul 08 18:10:27 birdnet_recording.sh[395]: ++ APPRISE_NOTIFY_EACH_DETECTION=0 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ APPRISE_NOTIFY_NEW_SPECIES=1 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ DATABASE_LANG=en Jul 08 18:10:27 birdnet_recording.sh[395]: ++ FLICKR_FILTER_EMAIL= Jul 08 18:10:27 birdnet_recording.sh[395]: ++ APPRISE_NOTIFY_NEW_SPECIES_EACH_DAY=1 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ APPRISE_WEEKLY_REPORT=1 Jul 08 18:10:27 birdnet_recording.sh[395]: ++ SILENCE_UPDATE_INDICATOR=0 Jul 08 18:10:27 birdnet_recording.sh[395]: + '[' -z 15 ']' Jul 08 18:10:27 birdnet_recording.sh[395]: + '[' '!' -z ']' Jul 08 18:10:27 birdnet_recording.sh[395]: + pulseaudio --check Jul 08 18:10:27 birdnet_recording.sh[450]: E: [pulseaudio] core-util.c: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 18:10:27 birdnet_recording.sh[395]: + pulseaudio --start Jul 08 18:10:27 birdnet_recording.sh[536]: E: [autospawn] core-util.c: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 18:10:27 birdnet_recording.sh[536]: W: [autospawn] lock-autospawn.c: Cannot access autospawn lock. Jul 08 18:10:27 birdnet_recording.sh[536]: E: [pulseaudio] main.c: Failed to acquire autospawn lock Jul 08 18:10:27 birdnet_recording.sh[395]: + pgrep arecord Jul 08 18:10:27 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:27 birdnet_recording.sh[552]: ++ netstat -tulpn Jul 08 18:10:27 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:28 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:28 birdnet_recording.sh[645]: ++ netstat -tulpn Jul 08 18:10:28 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:29 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:29 birdnet_recording.sh[661]: ++ netstat -tulpn Jul 08 18:10:29 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:30 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:30 birdnet_recording.sh[675]: ++ netstat -tulpn Jul 08 18:10:30 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:31 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:31 birdnet_recording.sh[694]: ++ netstat -tulpn Jul 08 18:10:31 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:32 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:32 birdnet_recording.sh[706]: ++ netstat -tulpn Jul 08 18:10:32 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:33 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:33 birdnet_recording.sh[714]: ++ netstat -tulpn Jul 08 18:10:33 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:34 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:34 birdnet_recording.sh[768]: ++ netstat -tulpn Jul 08 18:10:34 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:35 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:35 birdnet_recording.sh[784]: ++ netstat -tulpn Jul 08 18:10:35 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:36 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:36 birdnet_recording.sh[792]: ++ netstat -tulpn Jul 08 18:10:36 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:37 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:37 birdnet_recording.sh[811]: ++ netstat -tulpn Jul 08 18:10:37 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:38 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:38 birdnet_recording.sh[835]: ++ netstat -tulpn Jul 08 18:10:38 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:39 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:39 birdnet_recording.sh[844]: ++ netstat -tulpn Jul 08 18:10:39 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:40 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:40 birdnet_recording.sh[852]: ++ netstat -tulpn Jul 08 18:10:40 birdnet_recording.sh[395]: + sleep 1 Jul 08 18:10:40 python3[396]: INFO: Created TensorFlow Lite XNNPACK delegate for CPU. Jul 08 18:10:40 python3[396]: INFO: Created TensorFlow Lite delegate for select TF ops. Jul 08 18:10:40 python3[396]: INFO: TfLiteFlexDelegate delegate: 1 nodes delegated out of 182 nodes with 1 partitions. Jul 08 18:10:41 birdnet_recording.sh[395]: + grep 5050 /dev/fd/63 Jul 08 18:10:41 birdnet_recording.sh[935]: ++ netstat -tulpn Jul 08 18:10:41 birdnet_recording.sh[936]: tcp 0 0 127.0.0.1:5050 0.0.0.0: LISTEN 396/python3 Jul 08 18:10:41 birdnet_recording.sh[395]: + '[' -z default ']' Jul 08 18:10:41 birdnet_recording.sh[395]: + arecord -f S16_LE -c2 -r48000 -t wav --max-file-time 15 -D default --use-strftime /BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav Jul 08 18:10:41 birdnet_recording.sh[938]: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 18:10:41 birdnet_recording.sh[938]: arecord: main:830: audio open error: No such file or directory Jul 08 18:10:41 systemd[1]: birdnet_recording.service: Main process exited, code=exited, status=1/FAILURE Jul 08 18:10:41 systemd[1]: birdnet_recording.service: Failed with result 'exit-code'. Jul 08 18:10:41 birdnet_analysis.sh[397]: Files loaded Jul 08 18:10:58 birdnet_analysis.sh[397]: RECORDING_LENGTH set to 15 Jul 08 18:10:58 birdnet_analysis.sh[397]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:10:16.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:10:16.wav.csv --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 18:10:58 python3[396]: LOADING TF LITE MODEL... DONE! Jul 08 18:10:59 python3[396]: READING AUDIO DATA... DONE! READ 2 CHUNKS. Jul 08 18:10:59 python3[396]: Date-time: 2022-07-08 18:10:16 Jul 08 18:11:00 env[1007]: Line = 2022-07-08-birdnet-18:10:01.wav.csv Jul 08 18:11:01 systemd[1]: birdnet_recording.service: Scheduled restart job, restart counter is at 1. Jul 08 18:11:01 systemd[1]: Stopped BirdNET Recording. Jul 08 18:11:01 systemd[1]: Started BirdNET Recording. Jul 08 18:11:01 birdnet_recording.sh[1032]: + source /etc/birdnet/birdnet.conf Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ PUSHED_APP_SECRET= Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ RECS_DIR=/BirdSongs Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ REC_CARD=default Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ PROCESSED=/BirdSongs/Processed Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ EXTRACTED=/BirdSongs/Extracted Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ OVERLAP=0.0 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ CONFIDENCE=0.65 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ SENSITIVITY=1.25 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ CHANNELS=2 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ FULL_DISK=purge Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ PRIVACY_MODE=on Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ RECORDING_LENGTH=15 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ EXTRACTION_LENGTH= Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ AUDIOFMT=mp3 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ LAST_RUN= Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ THIS_RUN= Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ IDFILE=/BirdNET-Pi/IdentifiedSoFar.txt Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ PRIVACY_THRESHOLD=3 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ APPRISE_NOTIFICATION_TITLE='New BirdNET-Pi Detection' Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ APPRISE_NOTIFICATION_BODY='A $sciname $comname was just detected with a confidence of $confidence' Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ APPRISE_NOTIFY_EACH_DETECTION=0 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ APPRISE_NOTIFY_NEW_SPECIES=1 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ DATABASE_LANG=en Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ FLICKR_FILTER_EMAIL= Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ APPRISE_NOTIFY_NEW_SPECIES_EACH_DAY=1 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ APPRISE_WEEKLY_REPORT=1 Jul 08 18:11:01 birdnet_recording.sh[1032]: ++ SILENCE_UPDATE_INDICATOR=0 Jul 08 18:11:01 birdnet_recording.sh[1032]: + '[' -z 15 ']' Jul 08 18:11:01 birdnet_recording.sh[1032]: + '[' '!' -z ']' Jul 08 18:11:01 birdnet_recording.sh[1032]: + pulseaudio --check Jul 08 18:11:01 systemd[1]: birdnet_recording.service: Main process exited, code=exited, status=1/FAILURE Jul 08 18:11:01 birdnet_recording.sh[1033]: E: [pulseaudio] core-util.c: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 18:11:01 systemd[1]: birdnet_recording.service: Failed with result 'exit-code'. Jul 08 18:11:01 birdnet_recording.sh[1032]: + pulseaudio --start Jul 08 18:11:01 birdnet_recording.sh[1034]: E: [autospawn] core-util.c: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 18:11:01 birdnet_recording.sh[1034]: W: [autospawn] lock-autospawn.c: Cannot access autospawn lock. Jul 08 18:11:01 birdnet_recording.sh[1034]: E: [pulseaudio] main.c: Failed to acquire autospawn lock Jul 08 18:11:01 birdnet_recording.sh[1032]: + pgrep arecord Jul 08 18:11:01 birdnet_recording.sh[1032]: + grep 5050 /dev/fd/63 Jul 08 18:11:01 birdnet_recording.sh[1037]: ++ netstat -tulpn Jul 08 18:11:01 birdnet_recording.sh[1038]: tcp 0 0 127.0.0.1:5050 0.0.0.0: LISTEN 396/python3 Jul 08 18:11:01 birdnet_recording.sh[1032]: + '[' -z default ']' Jul 08 18:11:01 birdnet_recording.sh[1032]: + arecord -f S16_LE -c2 -r48000 -t wav --max-file-time 15 -D default --use-strftime /BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav Jul 08 18:11:01 birdnet_recording.sh[1040]: Failed to create secure directory (/run/user/1000/pulse): No such file or directory Jul 08 18:11:01 birdnet_recording.sh[1040]: arecord: main:830: audio open error: No such file or directory Jul 08 18:11:02 birdnet_analysis.sh[1002]: 0.0;3.0-('Human_Human', 0.0) Jul 08 18:11:02 birdnet_analysis.sh[1002]: 3.0;6.0-('Human_Human', 0.0) Jul 08 18:11:02 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 18:11:04 systemd[1]: birdnet_recording.service: Scheduled restart job, restart counter is at 2. Jul 08 18:11:04 systemd[1]: Stopped BirdNET Recording. Jul 08 18:11:04 systemd[1]: Started BirdNET Recording. Jul 08 18:11:04 birdnet_recording.sh[1098]: + source /etc/birdnet/birdnet.conf Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ PUSHED_APP_SECRET= Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ RECS_DIR=/BirdSongs Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ REC_CARD=default Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ PROCESSED=/BirdSongs/Processed Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ EXTRACTED=/BirdSongs/Extracted Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ OVERLAP=0.0 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ CONFIDENCE=0.65 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ SENSITIVITY=1.25 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ CHANNELS=2 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ FULL_DISK=purge Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ PRIVACY_MODE=on Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ RECORDING_LENGTH=15 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ EXTRACTION_LENGTH= Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ AUDIOFMT=mp3 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ LAST_RUN= Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ THIS_RUN= Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ IDFILE=/BirdNET-Pi/IdentifiedSoFar.txt Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ PRIVACY_THRESHOLD=3 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ APPRISE_NOTIFICATION_TITLE='New BirdNET-Pi Detection' Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ APPRISE_NOTIFICATION_BODY='A $sciname $comname was just detected with a confidence of $confidence' Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ APPRISE_NOTIFY_EACH_DETECTION=0 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ APPRISE_NOTIFY_NEW_SPECIES=1 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ DATABASE_LANG=en Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ FLICKR_FILTER_EMAIL= Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ APPRISE_NOTIFY_NEW_SPECIES_EACH_DAY=1 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ APPRISE_WEEKLY_REPORT=1 Jul 08 18:11:04 birdnet_recording.sh[1098]: ++ SILENCE_UPDATE_INDICATOR=0 Jul 08 18:11:04 birdnet_recording.sh[1098]: + '[' -z 15 ']' Jul 08 18:11:04 birdnet_recording.sh[1098]: + '[' '!' -z ']' Jul 08 18:11:04 birdnet_recording.sh[1098]: + pulseaudio --check Jul 08 18:11:04 birdnet_recording.sh[1098]: + pgrep arecord Jul 08 18:11:04 birdnet_recording.sh[1098]: + grep 5050 /dev/fd/63 Jul 08 18:11:04 birdnet_recording.sh[1104]: ++ netstat -tulpn Jul 08 18:11:04 birdnet_recording.sh[1105]: tcp 0 0 127.0.0.1:5050 0.0.0.0:* LISTEN 396/python3 Jul 08 18:11:04 birdnet_recording.sh[1098]: + '[' -z default ']' Jul 08 18:11:04 birdnet_recording.sh[1098]: + arecord -f S16_LE -c2 -r48000 -t wav --max-file-time 15 -D default --use-strftime /BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav Jul 08 18:11:04 birdnet_recording.sh[1107]: Recording WAVE '/BirdSongs/%B-%Y/%d-%A/%F-birdnet-%H:%M:%S.wav' : Signed 16 bit Little Endian, Rate 48000 Hz, Stereo Jul 08 18:11:04 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 1. Jul 08 18:11:04 systemd[1]: Stopped BirdNET Analysis. Jul 08 18:11:04 systemd[1]: Started BirdNET Analysis. Jul 08 18:11:05 birdnet_analysis.sh[1109]: Files loaded Jul 08 18:11:06 birdnet_analysis.sh[1109]: RECORDING_LENGTH set to 15 Jul 08 18:11:06 env[1159]: Line = 2022-07-08-birdnet-18:10:16.wav.csv Jul 08 18:11:20 birdnet_analysis.sh[1109]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:11:04.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:11:04.wav.csv --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 18:11:20 python3[396]: ANALYZING AUDIO... DONE! Time 3.3 SECONDS Jul 08 18:11:20 python3[396]: WRITING RESULTS TO /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:10:16.wav.csv ... DONE! WROTE 0 RESULTS. Jul 08 18:11:21 python3[396]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 18:11:21 python3[396]: Date-time: 2022-07-08 18:11:04 Jul 08 18:11:22 birdnet_analysis.sh[1314]: 0.0;3.0-('Scolopax minor_American Woodcock', 0.044873666) Jul 08 18:11:22 birdnet_analysis.sh[1314]: 3.0;6.0-('Scolopax minor_American Woodcock', 0.045488626) Jul 08 18:11:22 birdnet_analysis.sh[1314]: 6.0;9.0-('Scolopax minor_American Woodcock', 0.037619267) Jul 08 18:11:22 birdnet_analysis.sh[1314]: 9.0;12.0-('Human_Human', 0.0) Jul 08 18:11:22 birdnet_analysis.sh[1314]: 12.0;15.0-('Scolopax minor_American Woodcock', 0.051918905) Jul 08 18:11:22 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 18:11:24 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 2. Jul 08 18:11:24 systemd[1]: Stopped BirdNET Analysis. Jul 08 18:11:24 systemd[1]: Started BirdNET Analysis. Jul 08 18:11:25 birdnet_analysis.sh[1339]: Files loaded Jul 08 18:11:26 birdnet_analysis.sh[1339]: RECORDING_LENGTH set to 15 Jul 08 18:11:28 env[1440]: Line = 2022-07-08-birdnet-18:11:04.wav.csv Jul 08 18:11:34 birdnet_analysis.sh[1339]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:11:19.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:11:19.wav.csv --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 18:11:34 python3[396]: ANALYZING AUDIO... DONE! Time 1.6 SECONDS Jul 08 18:11:34 python3[396]: WRITING RESULTS TO /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:11:04.wav.csv ... DONE! WROTE 0 RESULTS. Jul 08 18:11:34 python3[396]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 18:11:34 python3[396]: Date-time: 2022-07-08 18:11:19 Jul 08 18:11:36 birdnet_analysis.sh[1508]: 0.0;3.0-('Human_Human', 0.0) Jul 08 18:11:36 birdnet_analysis.sh[1508]: 3.0;6.0-('Human_Human', 0.0) Jul 08 18:11:36 birdnet_analysis.sh[1508]: 6.0;9.0-('Human_Human', 0.0) Jul 08 18:11:36 birdnet_analysis.sh[1508]: 9.0;12.0-('Human_Human', 0.0) Jul 08 18:11:36 birdnet_analysis.sh[1508]: 12.0;15.0-('Human_Human', 0.0) Jul 08 18:11:36 systemd[1]: birdnet_analysis.service: Succeeded. Jul 08 18:11:38 systemd[1]: birdnet_analysis.service: Scheduled restart job, restart counter is at 3. Jul 08 18:11:38 systemd[1]: Stopped BirdNET Analysis. Jul 08 18:11:38 systemd[1]: Started BirdNET Analysis. Jul 08 18:11:39 birdnet_analysis.sh[1548]: Files loaded Jul 08 18:11:40 birdnet_analysis.sh[1548]: RECORDING_LENGTH set to 15 Jul 08 18:11:41 env[1602]: Line = 2022-07-08-birdnet-18:11:19.wav.csv Jul 08 18:11:50 birdnet_analysis.sh[1548]: /BirdNET-Pi/birdnet/bin/python3 /BirdNET-Pi/scripts\analyze.py --i /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:11:34.wav --o /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:11:34.wav.csv --week 26 --overlap 0.0 --sensitivity 1.25 --min_conf 0.65 --birdweather_id "IN_USE" Jul 08 18:11:50 python3[396]: ANALYZING AUDIO... DONE! Time 1.7 SECONDS Jul 08 18:11:50 python3[396]: WRITING RESULTS TO /BirdSongs/July-2022/08-Friday/2022-07-08-birdnet-18:11:19.wav.csv ... DONE! WROTE 0 RESULTS. Jul 08 18:11:50 python3[396]: READING AUDIO DATA... DONE! READ 5 CHUNKS. Jul 08 18:11:50 python3[396]: Date-time: 2022-07-08 18:11:34 birdnetpi:~/BirdNET-Pi$

b0rnwr0ng commented 2 years ago

Everything seems ok but nondetections which is highly unusual. Restarted live and got:

` Restarting icecast2 (via systemctl): icecast2.service. Unit restart.service could not be found.

`

ehpersonal38 commented 2 years ago

Having this same exact problem since commit ce017a9baea92169f44a9ed5d277dd777ada9901. The problem is that scripts/cleanup.sh is detecting a backlog 10 or more files in /home/pi/BirdSongs/Processed, and subsequently stopping all services. But a few lines above that script, it only ever deletes files from Processed if theres 100 or more? Can you shed any light @mcguirepr89 ?

For now I'm commenting out the last 3 lines in scripts/cleanup.sh and then running bash ~/BirdNET-Pi/scripts/restart_services.sh

b0rnwr0ng commented 2 years ago

I wound up doing the inatall_birdnet script but also did an update after this comment above, seems ok now? Ill keep an eye out