Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

[Problem]: (code=exited, status=203/EXEC) #1809

Closed
2 tasks done
DifferentComputers opened this issue Feb 23, 2024 · 12 comments
Closed
2 tasks done

[Problem]: (code=exited, status=203/EXEC) #1809

DifferentComputers opened this issue Feb 23, 2024 · 12 comments
Labels
Stale troubleshooting

Comments

@DifferentComputers
Copy link

What happened?

Shairport was installed and running. ran some updates. Now it only runs if I issue a shairport-sync -v command!

Relevant log output

$ systemctl status shairport-sync
× shairport-sync.service - Shairport Sync - AirPlay Audio Receiver
     Loaded: loaded (/lib/systemd/system/shairport-sync.service; enabled; vendor preset: enabled)
     Active: failed (Result: exit-code) since Fri 2024-02-23 10:48:28 EST; 17s ago
       Docs: man:shairport-sync(7)
             file:///usr/share/doc/shairport-sync/README.md.gz
             https://github.com/mikebrady/shairport-sync
    Process: 3081 ExecStart=/usr/bin/shairport-sync $DAEMON_ARGS (code=exited, status=203/EXEC)
   Main PID: 3081 (code=exited, status=203/EXEC)
        CPU: 1ms

Feb 23 10:48:28 SlabBook systemd[1]: shairport-sync.service: Scheduled restart job, restart counter is at 5.
Feb 23 10:48:28 SlabBook systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Feb 23 10:48:28 SlabBook systemd[1]: shairport-sync.service: Start request repeated too quickly.
Feb 23 10:48:28 SlabBook systemd[1]: shairport-sync.service: Failed with result 'exit-code'.
Feb 23 10:48:28 SlabBook systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver.

System Information.

Linux SlabBook 6.5.0-21-generic #21~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Fri Feb 9 13:32:52 UTC 2 x86_64 x86_64 x86_64 GNU/Linux

Configuration Information.

>> Display Config Start.

From "uname -a":
 Linux SlabBook 6.5.0-21-generic #21~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Fri Feb  9 13:32:52 UTC 2 x86_64 x86_64 x86_64 GNU/Linux

From /etc/os-release:
 Ubuntu 22.04.4 LTS

Shairport Sync Version String:
 4.3.2-2-g165431a8-AirPlay2-smi10-OpenSSL-Avahi-ALSA-soxr-sysconfdir:/etc

Command Line:
 shairport-sync --displayConfig

Configuration File:
 /etc/shairport-sync.conf

Configuration File Settings:
 alsa : 
 {
   output_device = "hw:CARD=HDMI,DEV=3";
 };

>> Display Config End.

If I run this command:
$ shairport-sync -v
         0.005000132 "shairport.c:2252" Startup in AirPlay 2 mode, with features 0x405c4a00,0x1c340 on device "3c:15:c2:bf:45:60".
         0.000042303 "shairport.c:2291" Version String: "4.3.2-2-g165431a8-AirPlay2-smi10-OpenSSL-Avahi-ALSA-soxr-sysconfdir:/etc"
         0.000005519 "shairport.c:2310" Command Line: "shairport-sync -v".
         0.000053788 "shairport.c:2346" Log Verbosity is 1.
         0.000018570 "audio_alsa.c:1021" alsa: alsa_maximum_stall_time of 0.200000 sec.
         0.000018088 "audio_alsa.c:1310" alsa: disable_standby_mode is "never".
         0.000006459 "audio_alsa.c:1314" alsa: disable_standby_mode_silence_threshold is 0.040000 seconds.
         0.000006083 "audio_alsa.c:1316" alsa: disable_standby_mode_silence_scan_interval is 0.004000 seconds.
         0.000011182 "audio_alsa.c:1356" alsa: output device name is "hw:CARD=HDMI,DEV=3".
         0.000125485 "shairport.c:2394" disable_resend_requests is off.
         0.000010556 "shairport.c:2395" diagnostic_drop_packet_fraction is 0.000000. A value of 0.0 means no packets will be dropped deliberately.
         0.000007775 "shairport.c:2399" statistics_requester status is 0.
         0.000005872 "shairport.c:2404" rtsp listening port is 7000.
         0.000008360 "shairport.c:2405" udp base port is 6001.
         0.000007182 "shairport.c:2406" udp port range is 10.
         0.000004720 "shairport.c:2407" player name is "SlabBook".
         0.000006493 "shairport.c:2408" backend is "alsa".
         0.000007373 "shairport.c:2409" run_this_before_play_begins action is "(null)".
         0.000006865 "shairport.c:2410" run_this_after_play_ends action is "(null)".
         0.000005814 "shairport.c:2411" wait-cmd status is 0.
         0.000006328 "shairport.c:2412" run_this_before_play_begins may return output is 0.
         0.000005942 "shairport.c:2413" run_this_if_an_unfixable_error_is_detected action is "(null)".
         0.000005678 "shairport.c:2415" run_this_before_entering_active_state action is  "(null)".
         0.000005725 "shairport.c:2417" run_this_after_exiting_active_state action is  "(null)".
         0.000005487 "shairport.c:2419" active_state_timeout is  10.000000 seconds.
         0.000007216 "shairport.c:2420" mdns backend "(null)".
         0.000004937 "shairport.c:2422" interpolation setting is "auto".
         0.000005507 "shairport.c:2426" interpolation soxr_delay_threshold is 30000000.
         0.000005733 "shairport.c:2427" resync time is 0.050000 seconds.
         0.000006090 "shairport.c:2428" resync recovery time is 0.100000 seconds.
         0.000005858 "shairport.c:2429" allow a session to be interrupted: 0.
         0.000005450 "shairport.c:2430" busy timeout time is 0.
         0.000005295 "shairport.c:2431" drift tolerance is 0.002000 seconds.
         0.000005857 "shairport.c:2432" password is "(null)".
         0.000005293 "shairport.c:2433" default airplay volume is: -24.000000.
         0.000006744 "shairport.c:2434" high threshold airplay volume is: -16.000000.
         0.000006156 "shairport.c:2436" check for higher-than-threshold volume for new play session is disabled.
         0.000005444 "shairport.c:2442" ignore_volume_control is 0.
         0.000005440 "shairport.c:2446" volume_max_db is not set
         0.000090242 "shairport.c:2447" volume range in dB (zero means use the range specified by the mixer): 0.
         0.000006626 "shairport.c:2449" volume_range_combined_hardware_priority (1 means hardware mixer attenuation is used first) is 0.
         0.000003917 "shairport.c:2453" playback_mode is 0 (0-stereo, 1-mono, 1-reverse_stereo, 2-both_left, 3-both_right).
         0.000003960 "shairport.c:2455" disable_synchronization is 0.
         0.000003983 "shairport.c:2456" use_mmap_if_available is 1.
         0.000005379 "shairport.c:2457" output_format automatic selection is enabled.
         0.000005513 "shairport.c:2461" output_rate automatic selection is enabled.
         0.000005892 "shairport.c:2465" audio backend desired buffer length is 0.200000 seconds.
         0.000006360 "shairport.c:2467" audio_backend_buffer_interpolation_threshold_in_seconds is 0.120000 seconds.
         0.000006613 "shairport.c:2469" audio backend latency offset is 0.000000 seconds.
         0.000006822 "shairport.c:2471" audio backend silence lead-in time is "auto".
         0.000005893 "shairport.c:2475" zeroconf regtype is "_raop._tcp".
         0.000005932 "shairport.c:2476" decoders_supported field is 1.
         0.000006051 "shairport.c:2477" use_apple_decoder is 0.
         0.000005967 "shairport.c:2478" alsa_use_hardware_mute is 0.
         0.000005475 "shairport.c:2482" no special mdns service interface was requested.
         0.000012457 "shairport.c:2485" configuration file name "/etc/shairport-sync.conf" resolves to "/etc/shairport-sync.conf".
         0.000006193 "shairport.c:2515" loudness is 0.
         0.000005887 "shairport.c:2516" loudness reference level is -20.000000
         0.000117505 "shairport.c:2643" NQPTP is online.
         1.500808735 "shairport.c:255" "soxr" interpolation has been chosen.

The app runs, Airplay sees it and it streams! But if I exit out or close the terminal window, shairport stops.

PulseAudio or PipeWire installed?

  • Check if your system uses a Sound Server.

How did you install Shairport Sync?

Built from source

Check previous issues

  • Confirm
@DifferentComputers
Copy link
Author

Looks like I spoke too soon about -v keeping the connection up. It stops running after a minute or so.

@klemensn klemensn mentioned this issue Feb 24, 2024
Merged
@mikebrady
Copy link
Owner

Thanks for the post. It is hard to see what might be happening from the information and logs, though it may be something to do the sound server installed (is it PulseAudio or PipeWire, BTW?).

Let me suggest setting the log_verbosity to 1 in the configuration file and restarting. The behaviour of Shairport Sync should then be logged in the system log, and it may give a clue as to why it's crashing. If you could post the log here, it would be very useful.

@DifferentComputers
Copy link
Author

DifferentComputers commented Mar 3, 2024 •
edited

Pretty sure I saw info on PulseAudio running, but I'm unsure. How would I tell?

Here's the syslog immediately after a cold boot and with shairport set to run automatically in systemctl. I greped for "shair" and got nothing.

Mar  3 00:00:19 SlabBook systemd[1]: rsyslog.service: Sent signal SIGHUP to main process 694 (rsyslogd) on client request.
Mar  3 00:00:19 SlabBook systemd[1]: logrotate.service: Deactivated successfully.
Mar  3 00:00:19 SlabBook systemd[1]: Finished Rotate log files.
Mar  3 00:00:19 SlabBook systemd[1]: logrotate.service: Consumed 17.500s CPU time.
Mar  3 00:09:01 SlabBook CRON[116048]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 00:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 00:09:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 00:09:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 00:17:01 SlabBook CRON[116143]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 00:17:29 SlabBook gnome-shell[1013]: st_widget_get_theme_node called on the widget [0x560e58c1e230 Gjs_ubuntu-dock_ubuntu_com_docking_DashToDock.left shrink 
running-dots fixed extended:insensitive "dashtodockContainer"] which is not in the stage.
Mar  3 00:17:29 SlabBook gnome-shell[1013]: Spurious clutter_actor_allocate called for actor 0x560e58c1e230/<dashtodockContainer>[<Gjs_ubuntu-dock_ubuntu_com_dockin
g_DashToDock>:0x560e58c1e230] which isn't a descendent of the stage!
Mar  3 00:17:29 SlabBook rtkit-daemon[902]: Supervising 7 threads of 4 processes of 1 users.
Mar  3 00:17:29 SlabBook rtkit-daemon[902]: Successfully made thread 116164 of process 887 owned by '1000' RT at priority 5.
Mar  3 00:17:29 SlabBook rtkit-daemon[902]: Supervising 8 threads of 4 processes of 1 users.
Mar  3 00:17:29 SlabBook gnome-shell[1013]: Window manager warning: Overwriting existing binding of keysym 31 with keysym 31 (keycode a).
Mar  3 00:17:29 SlabBook gnome-shell[1013]: Window manager warning: Overwriting existing binding of keysym 37 with keysym 37 (keycode 10).
Mar  3 00:17:29 SlabBook gnome-shell[1013]: Window manager warning: Overwriting existing binding of keysym 33 with keysym 33 (keycode c).
Mar  3 00:17:29 SlabBook gnome-shell[1013]: Window manager warning: Overwriting existing binding of keysym 34 with keysym 34 (keycode d).
Mar  3 00:17:29 SlabBook gnome-shell[1013]: Window manager warning: Overwriting existing binding of keysym 35 with keysym 35 (keycode e).
Mar  3 00:17:29 SlabBook gnome-shell[1013]: Window manager warning: Overwriting existing binding of keysym 36 with keysym 36 (keycode f).
Mar  3 00:17:29 SlabBook gnome-shell[1013]: Window manager warning: Overwriting existing binding of keysym 32 with keysym 32 (keycode b).
Mar  3 00:17:29 SlabBook gnome-shell[1013]: Window manager warning: Overwriting existing binding of keysym 38 with keysym 38 (keycode 11).
Mar  3 00:17:29 SlabBook gnome-shell[1013]: Window manager warning: Overwriting existing binding of keysym 39 with keysym 39 (keycode 12).
Mar  3 00:26:30 SlabBook NetworkManager[673]: <info>  [1709443590.9691] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 00:28:53 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 00:39:01 SlabBook CRON[116309]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 00:39:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 00:39:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 00:39:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 00:59:03 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 01:05:17 SlabBook NetworkManager[673]: <info>  [1709445917.9804] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 01:09:01 SlabBook CRON[116553]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 01:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 01:09:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 01:09:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 01:17:01 SlabBook CRON[116650]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 01:29:13 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 01:39:01 SlabBook CRON[116797]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 01:39:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 01:39:02 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 01:39:02 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 01:41:45 SlabBook NetworkManager[673]: <info>  [1709448105.9661] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 01:59:23 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 02:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 02:09:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 02:09:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 02:09:01 SlabBook CRON[117077]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 02:17:01 SlabBook CRON[117126]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 02:20:23 SlabBook NetworkManager[673]: <info>  [1709450423.9576] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 02:29:33 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 02:39:01 SlabBook CRON[117266]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 02:39:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 02:39:02 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 02:39:02 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 02:57:48 SlabBook NetworkManager[673]: <info>  [1709452668.9619] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 02:59:43 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 03:09:01 SlabBook CRON[117519]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 03:09:01 SlabBook systemd[1]: Starting Refresh fwupd metadata and update motd...
Mar  3 03:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 03:09:02 SlabBook systemd[1]: fwupd-refresh.service: Deactivated successfully.
Mar  3 03:09:02 SlabBook systemd[1]: Finished Refresh fwupd metadata and update motd.
Mar  3 03:09:02 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 03:09:02 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 03:10:01 SlabBook CRON[117576]: (root) CMD (test -e /run/systemd/system || SERVICE_MODE=1 /sbin/e2scrub_all -A -r)
Mar  3 03:10:31 SlabBook systemd[1]: Starting Online ext4 Metadata Check for All Filesystems...
Mar  3 03:10:31 SlabBook systemd[1]: e2scrub_all.service: Deactivated successfully.
Mar  3 03:10:31 SlabBook systemd[1]: Finished Online ext4 Metadata Check for All Filesystems.
Mar  3 03:17:01 SlabBook CRON[117624]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 03:28:49 SlabBook systemd[1]: Starting Message of the Day...
Mar  3 03:28:49 SlabBook systemd[1]: motd-news.service: Deactivated successfully.
Mar  3 03:28:49 SlabBook systemd[1]: Finished Message of the Day.
Mar  3 03:29:53 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 03:30:01 SlabBook CRON[117712]: (root) CMD (test -e /run/systemd/system || SERVICE_MODE=1 /usr/lib/x86_64-linux-gnu/e2fsprogs/e2scrub_all_cron)
Mar  3 03:36:20 SlabBook NetworkManager[673]: <info>  [1709454980.9769] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 03:39:02 SlabBook CRON[117772]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 03:39:02 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 03:39:02 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 03:39:02 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 03:56:01 SlabBook CRON[117928]: (root) CMD (   PATH="$PATH:/usr/sbin:/usr/local/bin/" pihole updateGravity >/var/log/pihole/pihole_updateGravity.log || cat /
var/log/pihole/pihole_updateGravity.log)
Mar  3 04:00:03 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 04:09:01 SlabBook CRON[118127]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 04:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 04:09:02 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 04:09:02 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 04:12:50 SlabBook snapd[700]: storehelpers.go:791: cannot refresh: snap has no updates available: "bare", "core20", "core22", "curl", "firefox", "gnome-3-38-
2004", "gnome-42-2204", "gtk-common-themes", "snap-store", "snapd", "snapd-desktop-integration"
Mar  3 04:14:08 SlabBook NetworkManager[673]: <info>  [1709457248.9764] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 04:17:01 SlabBook CRON[118222]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 04:30:13 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 04:39:00 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 04:39:00 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 04:39:00 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 04:39:01 SlabBook CRON[118473]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 04:51:44 SlabBook NetworkManager[673]: <info>  [1709459504.9637] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 05:00:24 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 05:09:01 SlabBook CRON[118673]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 05:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 05:09:02 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 05:09:02 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 05:17:01 SlabBook CRON[118767]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 05:30:03 SlabBook NetworkManager[673]: <info>  [1709461803.9532] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 05:30:34 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 05:39:02 SlabBook CRON[118909]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 05:39:02 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 05:39:02 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 05:39:02 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 06:00:44 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 06:08:45 SlabBook NetworkManager[673]: <info>  [1709464125.9918] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 06:09:01 SlabBook CRON[119160]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 06:09:14 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 06:09:14 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 06:09:14 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 06:17:01 SlabBook CRON[119249]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 06:25:01 SlabBook CRON[119298]: (root) CMD (test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.daily ))
Mar  3 06:30:54 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 06:39:01 SlabBook CRON[119392]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 06:39:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 06:39:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 06:39:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 06:46:41 SlabBook NetworkManager[673]: <info>  [1709466401.0028] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 06:47:01 SlabBook CRON[119485]: (root) CMD (test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.weekly ))
Mar  3 06:48:57 SlabBook systemd[1]: Starting Update APT News...
Mar  3 06:48:57 SlabBook systemd[1]: Starting Update the local ESM caches...
Mar  3 06:48:57 SlabBook systemd[1]: apt-news.service: Deactivated successfully.
Mar  3 06:48:57 SlabBook systemd[1]: Finished Update APT News.
Mar  3 06:48:58 SlabBook systemd[1]: esm-cache.service: Deactivated successfully.
Mar  3 06:48:58 SlabBook systemd[1]: Finished Update the local ESM caches.
Mar  3 06:49:01 SlabBook PackageKit: refresh-cache transaction /75_dadaecbd from uid 1000 finished with success after 4290ms
Mar  3 06:49:03 SlabBook snapd[700]: storehelpers.go:791: cannot refresh: snap has no updates available: "bare", "core20", "core22", "curl", "firefox", "gnome-3-38-
2004", "gnome-42-2204", "gtk-common-themes", "snap-store", "snapd", "snapd-desktop-integration"
Mar  3 06:59:45 SlabBook systemd[1]: Starting Daily apt upgrade and clean activities...
Mar  3 06:59:48 SlabBook systemd[1]: apt-daily-upgrade.service: Deactivated successfully.
Mar  3 06:59:48 SlabBook systemd[1]: Finished Daily apt upgrade and clean activities.
Mar  3 06:59:48 SlabBook systemd[1]: apt-daily-upgrade.service: Consumed 3.234s CPU time.
Mar  3 07:01:04 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 07:09:01 SlabBook CRON[120132]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 07:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 07:09:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 07:09:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 07:17:01 SlabBook CRON[120227]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 07:23:28 SlabBook NetworkManager[673]: <info>  [1709468608.9807] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 07:30:01 SlabBook CRON[120313]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/nul
l; fi)
Mar  3 07:31:14 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 07:32:31 SlabBook systemd[1]: Started Run anacron jobs.
Mar  3 07:32:31 SlabBook anacron[120327]: Anacron 2.3 started on 2024-03-03
Mar  3 07:32:31 SlabBook anacron[120327]: Will run job `cron.daily' in 5 min.
Mar  3 07:32:31 SlabBook anacron[120327]: Jobs will be executed sequentially
Mar  3 07:37:31 SlabBook anacron[120327]: Job `cron.daily' started
Mar  3 07:37:31 SlabBook anacron[120360]: Updated timestamp for job `cron.daily' to 2024-03-03
Mar  3 07:37:31 SlabBook cracklib: no dictionary update necessary.
Mar  3 07:37:31 SlabBook anacron[120327]: Job `cron.daily' terminated
Mar  3 07:37:31 SlabBook anacron[120327]: Normal exit (1 job run)
Mar  3 07:37:31 SlabBook systemd[1]: anacron.service: Deactivated successfully.
Mar  3 07:39:01 SlabBook CRON[120413]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 07:39:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 07:39:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 07:39:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 08:01:20 SlabBook NetworkManager[673]: <info>  [1709470880.9768] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 08:01:24 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 08:09:01 SlabBook CRON[120637]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 08:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 08:09:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 08:09:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 08:17:01 SlabBook CRON[120731]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 08:30:01 SlabBook CRON[120811]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/nul
l; fi)
Mar  3 08:30:19 SlabBook systemd[1]: Started Run anacron jobs.
Mar  3 08:30:19 SlabBook anacron[120816]: Anacron 2.3 started on 2024-03-03
Mar  3 08:30:19 SlabBook anacron[120816]: Normal exit (0 jobs run)
Mar  3 08:30:19 SlabBook systemd[1]: anacron.service: Deactivated successfully.
Mar  3 08:31:34 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 08:39:01 SlabBook CRON[120867]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 08:39:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 08:39:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 08:39:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 08:40:07 SlabBook NetworkManager[673]: <info>  [1709473207.9829] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 09:01:44 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 09:07:50 SlabBook snapd[700]: storehelpers.go:791: cannot refresh: snap has no updates available: "bare", "core20", "core22", "curl", "firefox", "gnome-3-38-
2004", "gnome-42-2204", "gtk-common-themes", "snap-store", "snapd", "snapd-desktop-integration"
Mar  3 09:09:01 SlabBook CRON[121089]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 09:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 09:09:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 09:09:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 09:17:01 SlabBook CRON[121182]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 09:17:13 SlabBook NetworkManager[673]: <info>  [1709475433.9617] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 09:23:16 SlabBook systemd[1]: Starting Daily man-db regeneration...
Mar  3 09:23:16 SlabBook systemd[1]: man-db.service: Deactivated successfully.
Mar  3 09:23:16 SlabBook systemd[1]: Finished Daily man-db regeneration.
Mar  3 09:30:01 SlabBook CRON[121262]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/nul
l; fi)
Mar  3 09:30:47 SlabBook systemd[1]: Started Run anacron jobs.
Mar  3 09:30:47 SlabBook anacron[121264]: Anacron 2.3 started on 2024-03-03
Mar  3 09:30:47 SlabBook anacron[121264]: Normal exit (0 jobs run)
Mar  3 09:30:47 SlabBook systemd[1]: anacron.service: Deactivated successfully.
Mar  3 09:31:54 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 09:39:01 SlabBook CRON[121319]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 09:39:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 09:39:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 09:39:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 09:55:07 SlabBook NetworkManager[673]: <info>  [1709477707.9704] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 10:02:04 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 10:09:01 SlabBook CRON[121538]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 10:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 10:09:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 10:09:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 10:17:01 SlabBook CRON[121629]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 10:30:01 SlabBook CRON[121773]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/nul
l; fi)
Mar  3 10:30:12 SlabBook systemd[1]: Started Run anacron jobs.
Mar  3 10:30:12 SlabBook anacron[121774]: Anacron 2.3 started on 2024-03-03
Mar  3 10:30:12 SlabBook anacron[121774]: Normal exit (0 jobs run)
Mar  3 10:30:12 SlabBook systemd[1]: anacron.service: Deactivated successfully.
Mar  3 10:32:14 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 10:32:31 SlabBook NetworkManager[673]: <info>  [1709479951.9619] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 10:39:01 SlabBook CRON[121829]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 10:39:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 10:39:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 10:39:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 10:47:35 SlabBook avahi-daemon[667]: Withdrawing address record for fd71:eb6d:4f25:7349:697e:ba99:1611:54b8 on wlp3s0.
Mar  3 10:47:35 SlabBook avahi-daemon[667]: Registering new address record for fd71:eb6d:4f25:7349:a45c:2744:8c85:b19 on wlp3s0.*.
Mar  3 10:54:15 SlabBook systemd[1]: Starting Download data for packages that failed at package install time...
Mar  3 10:54:15 SlabBook systemd[1]: update-notifier-download.service: Deactivated successfully.
Mar  3 10:54:15 SlabBook systemd[1]: Finished Download data for packages that failed at package install time.
Mar  3 11:02:24 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 11:04:02 SlabBook systemd[1]: Starting Cleanup of Temporary Directories...
Mar  3 11:04:02 SlabBook systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully.
Mar  3 11:04:02 SlabBook systemd[1]: Finished Cleanup of Temporary Directories.
Mar  3 11:09:01 SlabBook CRON[122058]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 11:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 11:09:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 11:09:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 11:11:17 SlabBook NetworkManager[673]: <info>  [1709482277.9832] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 11:17:01 SlabBook CRON[122148]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Mar  3 11:30:01 SlabBook CRON[122228]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/nul
l; fi)
Mar  3 11:32:34 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 11:33:17 SlabBook systemd[1]: Started Run anacron jobs.
Mar  3 11:33:17 SlabBook anacron[122250]: Anacron 2.3 started on 2024-03-03
Mar  3 11:33:17 SlabBook anacron[122250]: Normal exit (0 jobs run)
Mar  3 11:33:17 SlabBook systemd[1]: anacron.service: Deactivated successfully.
Mar  3 11:39:01 SlabBook CRON[122285]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 11:39:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 11:39:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 11:39:01 SlabBook systemd[1]: Finished Clean php session files.
Mar  3 11:43:49 SlabBook systemd[1]: Starting Refresh fwupd metadata and update motd...
Mar  3 11:43:49 SlabBook fwupdmgr[122354]: Updating lvfs
Mar  3 11:43:49 SlabBook fwupdmgr[122354]: Downloading…: 0%
Mar  3 11:43:49 SlabBook fwupdmgr[122354]: Idle…: 100%
Mar  3 11:43:49 SlabBook fwupdmgr[122354]: Idle…: 100%
Mar  3 11:43:49 SlabBook fwupdmgr[122354]: Successfully downloaded new metadata: 0 local devices supported
Mar  3 11:43:49 SlabBook systemd[1]: fwupd-refresh.service: Deactivated successfully.
Mar  3 11:43:49 SlabBook systemd[1]: Finished Refresh fwupd metadata and update motd.
Mar  3 11:49:36 SlabBook NetworkManager[673]: <info>  [1709484576.9545] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.68
Mar  3 12:02:44 SlabBook ubuntu-report[890]: level=error msg="data were not delivered successfully to metrics server, retrying in 1800s"
Mar  3 12:05:56 SlabBook systemd[1]: Starting Update APT News...
Mar  3 12:05:56 SlabBook systemd[1]: Starting Update the local ESM caches...
Mar  3 12:05:56 SlabBook systemd[1]: apt-news.service: Deactivated successfully.
Mar  3 12:05:56 SlabBook systemd[1]: Finished Update APT News.
Mar  3 12:05:57 SlabBook systemd[1]: esm-cache.service: Deactivated successfully.
Mar  3 12:05:57 SlabBook systemd[1]: Finished Update the local ESM caches.
Mar  3 12:09:01 SlabBook CRON[122961]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Mar  3 12:09:01 SlabBook systemd[1]: Starting Clean php session files...
Mar  3 12:09:01 SlabBook systemd[1]: phpsessionclean.service: Deactivated successfully.
Mar  3 12:09:01 SlabBook systemd[1]: Finished Clean php session files.```

@DifferentComputers
Copy link
Author

DifferentComputers commented Mar 3, 2024 •
edited

Ok, I restarted shairport-sync with shairport-sync and it was available to send audio to until I stopped the command in the terminal window.

Then I grepped the syslog and got Mar 3 12:23:26 SlabBook kernel: [783319.192385] shairport-sync[123108]: segfault at 18 ip 00007f9117c9a64c sp 00007f910d13ac10 error 4 in libc.so.6[7f9117c28000+195000] likely on CPU 0 (core 0, socket 0)

When I try sudo systemctl start shairport-sync it tries to start, sometimes becomes momentarily selectable from my airplay source, then fails.

@mikebrady
Copy link
Owner

Thanks.

There's nothing useful in that log, I'm afraid. If you're running a Linux with a "Desktop" GUI, then it's probably running PulseAudio or PipeWire. There's also a bit about the issues in PulseAudioAndPipeWire.md.

If you set the log_verbosity to 1 (maybe you've done that already) and restart then you may get some more information in the log.

@DifferentComputers
Copy link
Author

I did set log verbosity to 1 before trying this. that refers to /var/log/syslog right? is there a separate log for shairport-sync?

@mikebrady
Copy link
Owner

Dunno which log it goes in to. I use journalctl. Something like:

$ sudo journalctl | grep shair

@mikebrady
Copy link
Owner

Also, having set the log_verbosity to 1, you need to restart Shairport Sync.

@DifferentComputers
Copy link
Author

having tons of trouble following instructions at https://askubuntu.com/questions/814/how-to-run-scripts-on-start-up (all sorts of tries from there) to get shairport-sync to start automatically.

Anyone have this working in a GUI environment?

@DifferentComputers
Copy link
Author

DifferentComputers commented Mar 10, 2024 •
edited

Dunno which log it goes in to. I use journalctl. Something like:

$ sudo journalctl | grep shair

Back to this. This command resulted in a nearly infinite number of these entries:
Feb 22 06:53:10 SlabBook shairport-sync[1573]: warning: the alsa output_device "default" can not be found.
Feb 22 06:53:10 SlabBook shairport-sync[1573]: ALSA lib pcm_dmix.c:1032:(snd_pcm_dmix_open) unable to open slave

@mikebrady
Copy link
Owner

mikebrady commented Mar 10, 2024 •
edited

Thanks. You'll find more about this in PulseAudioAndPipeWire, specifically here, where is says: Per (5) above, the ALSA "default" device either won't exist or -- if it does exist -- won't work.

That looks like what the problem is.

@github-actions GitHub Actions
Copy link

This issue has been inactive for 28 days so will be closed 7 days from now. To prevent this, please remove the "stale" label or post a comment.

@github-actions github-actions bot added the Stale label May 10, 2024
@github-actions github-actions bot closed this as not planned Won't fix, can't repro, duplicate, stale May 17, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
Stale troubleshooting
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@mikebrady @DifferentComputers