Diff of the two buildlogs: -- --- b1/build.log 2024-04-26 19:25:11.136410170 +0000 +++ b2/build.log 2024-04-26 19:34:44.027979945 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Fri Apr 26 07:17:13 -12 2024 -I: pbuilder-time-stamp: 1714159033 +I: Current time: Sat Apr 27 09:25:32 +14 2024 +I: pbuilder-time-stamp: 1714159532 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -25,52 +25,84 @@ dpkg-source: info: unpacking mpv-mpris_0.7.1-1.debian.tar.xz I: using fakeroot in build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/24439/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/10102/tmp/hooks/D01_modify_environment starting +debug: Running on cbxi4a. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Apr 26 19:26 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/10102/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/10102/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='armhf' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="21" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=4 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='304cd5aedffa4f19960115e4fd7faea5' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='24439' - PS1='# ' - PS2='> ' + INVOCATION_ID=6db38885b9d5441ca45d06765e735af5 + LANG=C + LANGUAGE=it_CH:it + LC_ALL=C + MACHTYPE=arm-unknown-linux-gnueabihf + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnueabihf + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=10102 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.7KnAXW5P/pbuilderrc_EBMG --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.7KnAXW5P/b1 --logfile b1/build.log mpv-mpris_0.7.1-1.dsc' - SUDO_GID='113' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://10.0.0.15:3142/' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.7KnAXW5P/pbuilderrc_JXJD --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.7KnAXW5P/b2 --logfile b2/build.log mpv-mpris_0.7.1-1.dsc' + SUDO_GID=113 + SUDO_UID=107 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://10.0.0.15:3142/ I: uname -a - Linux virt64b 6.1.0-20-arm64 #1 SMP Debian 6.1.85-1 (2024-04-11) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-20-armmp #1 SMP Debian 6.1.85-1 (2024-04-11) armv7l GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Apr 25 07:43 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/24439/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Apr 25 07:47 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/10102/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -565,7 +597,7 @@ Get: 433 http://deb.debian.org/debian unstable/main armhf xauth armhf 1:1.1.2-1 [33.2 kB] Get: 434 http://deb.debian.org/debian unstable/main armhf xserver-common all 2:21.1.12-1 [2391 kB] Get: 435 http://deb.debian.org/debian unstable/main armhf xvfb armhf 2:21.1.12-1 [3024 kB] -Fetched 202 MB in 11s (19.3 MB/s) +Fetched 202 MB in 29s (7009 kB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libdbus-1-3:armhf. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19440 files and directories currently installed.) @@ -1965,8 +1997,8 @@ Setting up tzdata (2024a-3) ... Current default time zone: 'Etc/UTC' -Local time is now: Fri Apr 26 19:23:20 UTC 2024. -Universal Time is now: Fri Apr 26 19:23:20 UTC 2024. +Local time is now: Fri Apr 26 19:31:45 UTC 2024. +Universal Time is now: Fri Apr 26 19:31:45 UTC 2024. Run 'dpkg-reconfigure tzdata' if you wish to change it. Setting up libasound2-data (1.2.11-1) ... @@ -2350,7 +2382,11 @@ fakeroot is already the newest version (1.33-1). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/reproducible-path/mpv-mpris-0.7.1/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../mpv-mpris_0.7.1-1_source.changes +I: user script /srv/workspace/pbuilder/10102/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/10102/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/mpv-mpris-0.7.1/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../mpv-mpris_0.7.1-1_source.changes dpkg-buildpackage: info: source package mpv-mpris dpkg-buildpackage: info: source version 0.7.1-1 dpkg-buildpackage: info: source distribution unstable @@ -2360,7 +2396,7 @@ fakeroot debian/rules clean dh clean dh_auto_clean - make -j3 clean + make -j4 clean make[1]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1' rm -f mpris.so make -C test clean @@ -2383,12 +2419,12 @@ dh_autoreconf dh_auto_configure dh_auto_build - make -j3 "INSTALL=install --strip-program=true" + make -j4 "INSTALL=install --strip-program=true" make[1]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1' cc mpris.c -o mpris.so -g -O2 -Werror=implicit-function-declaration -ffile-prefix-map=/build/reproducible-path/mpv-mpris-0.7.1=. -fstack-protector-strong -fstack-clash-protection -Wformat -Werror=format-security -std=c99 -Wall -Wextra -O2 `pkg-config --cflags gio-2.0 gio-unix-2.0 glib-2.0 mpv` -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -D_TIME_BITS=64 -Wdate-time -D_FORTIFY_SOURCE=2 -Wl,-z,relro -Wl,-z,now `pkg-config --libs gio-2.0 gio-unix-2.0 glib-2.0` -shared -fPIC make[1]: Leaving directory '/build/reproducible-path/mpv-mpris-0.7.1' dh_auto_test - make -j3 test + make -j4 test make[1]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1' make -C test make[2]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1/test' @@ -2451,8 +2487,8 @@ + MPV_MPRIS_TEST_DBUS=./dbus + mkdir -p -m 0700 ./dbus + export -n MPV_MPRIS_TEST_DBUS MPV_MPRIS_TEST_XAUTH MPV_MPRIS_TEST_TMP -+ xvfb-run --help + grep -- --auto-display ++ xvfb-run --help + xvfb_auto=--auto-servernum + rm -f ./metadata.xvfb.log ./metadata.Xauthority + exec env MPV_MPRIS_TEST_NAME=metadata XDG_RUNTIME_DIR=./dbus TEMPDIR=. TMPDIR=. TEMP=. TMP=. dbus-run-session -- xvfb-run --auto-servernum --error-file ./metadata.xvfb.log -f ./metadata.Xauthority ./metadata @@ -2473,20 +2509,32 @@ ++ '[' -n ../mpris.so ']' ++ params+=("--load-scripts=no" "--script=$MPV_MPRIS_TEST_PLUGIN") ++ unset MPV_MPRIS_TEST_PLUGIN MPV_MPRIS_TEST_PLAY MPV_MPRIS_TEST_MPV_IPC MPV_MPRIS_TEST_LOG -++ echo DISPLAY=:100 -DISPLAY=:100 -++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-W0ypb4GNEl,guid=7df556a00699ae0276ad3886662bff69 -DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-W0ypb4GNEl,guid=7df556a00699ae0276ad3886662bff69 +++ echo DISPLAY=:99 +DISPLAY=:99 +++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-m2nDYbgMAE,guid=54690b4c867a23e2add64475662c018a +DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-m2nDYbgMAE,guid=54690b4c867a23e2add64475662c018a ++ rm -f ./metadata.mpv.ipc ./metadata.mpv.log ./metadata.socat.log ++ wait_for test -S ./metadata.mpv.ipc ++ timeout=0 ++ test -S ./metadata.mpv.ipc -++ mpv --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./metadata.mpv.log --input-ipc-server=./metadata.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga ++ '[' 0 -eq 60 ']' ++ sleep 1 +++ mpv --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./metadata.mpv.log --input-ipc-server=./metadata.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +++ awk '/statusline.*Paused/{print>"/dev/stdout";next} 1' ++ timeout=1 ++ test -S ./metadata.mpv.ipc +++ '[' 1 -eq 60 ']' +++ sleep 1 +++ timeout=2 +++ test -S ./metadata.mpv.ipc +++ '[' 2 -eq 60 ']' +++ sleep 1 +++ timeout=3 +++ test -S ./metadata.mpv.ipc +++ '[' 3 -eq 60 ']' +++ sleep 1 +++ timeout=4 +++ test -S ./metadata.mpv.ipc ++ wait_for playerctl_list_all_is_mpv ++ timeout=0 ++ playerctl_list_all_is_mpv @@ -2538,163 +2586,172 @@ [cplayer] Setting option 'msg-level' = 'cplayer=trace,mpris=trace' (flags = 8) [cplayer] Setting option 'log-file' = './metadata.mpv.log' (flags = 8) [cplayer] Setting option 'input-ipc-server' = './metadata.mpv.ipc' (flags = 8) -[ 0.029560] mpris: Loading cplugin script ../mpris.so... -[ 0.030199] cplayer: Waiting for scripts... -[ 0.057705] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.057799] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.057855] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.057918] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.058209] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] -[ 0.058272] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.058309] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] -[ 0.058341] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.060996] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] -[ 0.061134] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] -[ 0.061202] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.061231] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.061375] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] -[ 0.061517] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] -[ 0.061559] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] -[ 0.061616] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] -[ 0.062339] cplayer: Set property: user-data/osc/visibility="auto" -> 1 -[ 0.062372] cplayer: Run command: disable-section, flags=64, args=[name="input"] -[ 0.062398] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] -[ 0.062647] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.063276] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] -[ 0.063591] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.063636] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] -[ 0.063766] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.063947] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.064978] cplayer: Done loading scripts. -[ 0.065197] cplayer: Running hook: ytdl_hook/on_load -[ 0.066329] cplayer: Running hook: ytdl_hook/on_load -[ 0.066676] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.067278] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.067588] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] -[ 0.078681] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga -[ 0.088358] cplayer: Running hook: ytdl_hook/on_preloaded -[ 0.088583] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.088874] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) -[ 0.091502] cplayer: Starting playback... -[ 0.093017] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] -[ 0.093102] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.093157] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] -[ 0.093206] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.094644] cplayer: AO: [null] 48000Hz stereo 2ch floatp -[ 0.094674] cplayer: AO: Description: Null audio output -[ 0.096321] cplayer: audio ready -[ 0.096358] cplayer: starting audio playback -[ 0.096423] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof -[ 0.117038] statusline: A: 00:00:00 / 00:00:06 (0%) -[ 0.124743] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.124766] statusline: A: 00:00:00 / 00:00:06 (0%) -[ 0.167198] statusline: A: 00:00:00 / 00:00:06 (1%) -[ 0.217318] statusline: A: 00:00:00 / 00:00:06 (2%) -[ 0.267445] statusline: A: 00:00:00 / 00:00:06 (3%) -[ 0.317598] statusline: A: 00:00:00 / 00:00:06 (4%) -[ 0.417900] statusline: A: 00:00:00 / 00:00:06 (5%) -[ 0.468016] statusline: A: 00:00:00 / 00:00:06 (6%) -[ 0.518160] statusline: A: 00:00:00 / 00:00:06 (7%) -[ 0.568299] statusline: A: 00:00:00 / 00:00:06 (8%) -[ 0.618442] statusline: A: 00:00:00 / 00:00:06 (9%) -[ 0.718760] statusline: A: 00:00:00 / 00:00:06 (10%) -[ 0.768899] statusline: A: 00:00:00 / 00:00:06 (11%) -[ 0.819047] statusline: A: 00:00:00 / 00:00:06 (12%) -[ 0.869184] statusline: A: 00:00:00 / 00:00:06 (13%) -[ 0.969477] statusline: A: 00:00:00 / 00:00:06 (14%) -[ 1.019610] statusline: A: 00:00:00 / 00:00:06 (15%) -[ 1.069720] statusline: A: 00:00:00 / 00:00:06 (16%) -[ 1.144707] statusline: A: 00:00:01 / 00:00:06 (17%) -[ 1.195203] statusline: A: 00:00:01 / 00:00:06 (18%) -[ 1.245201] statusline: A: 00:00:01 / 00:00:06 (19%) -[ 1.344038] statusline: A: 00:00:01 / 00:00:06 (20%) -[ 1.394975] statusline: A: 00:00:01 / 00:00:06 (21%) -[ 1.493617] statusline: A: 00:00:01 / 00:00:06 (23%) -[ 1.543698] statusline: A: 00:00:01 / 00:00:06 (24%) -[ 1.644166] statusline: A: 00:00:01 / 00:00:06 (25%) -[ 1.694337] statusline: A: 00:00:01 / 00:00:06 (26%) -[ 1.744718] statusline: A: 00:00:01 / 00:00:06 (27%) -[ 1.843526] statusline: A: 00:00:01 / 00:00:06 (29%) -[ 1.944760] statusline: A: 00:00:01 / 00:00:06 (30%) -[ 1.994947] statusline: A: 00:00:01 / 00:00:06 (31%) -[ 2.045777] statusline: A: 00:00:01 / 00:00:06 (32%) -[ 2.095949] statusline: A: 00:00:01 / 00:00:06 (33%) -[ 2.146718] statusline: A: 00:00:02 / 00:00:06 (33%) -[ 2.245575] statusline: A: 00:00:02 / 00:00:06 (35%) -[ 2.295671] statusline: A: 00:00:02 / 00:00:06 (36%) -[ 2.345967] statusline: A: 00:00:02 / 00:00:06 (37%) -[ 2.396384] statusline: A: 00:00:02 / 00:00:06 (38%) -[ 2.495083] statusline: A: 00:00:02 / 00:00:06 (39%) -[ 2.545110] statusline: A: 00:00:02 / 00:00:06 (40%) -[ 2.595178] statusline: A: 00:00:02 / 00:00:06 (41%) -[ 2.645203] statusline: A: 00:00:02 / 00:00:06 (42%) -[ 2.745523] statusline: A: 00:00:02 / 00:00:06 (43%) -[ 2.795704] statusline: A: 00:00:02 / 00:00:06 (44%) -[ 2.847737] statusline: A: 00:00:02 / 00:00:06 (45%) -[ 2.897747] statusline: A: 00:00:02 / 00:00:06 (46%) -[ 2.947805] statusline: A: 00:00:02 / 00:00:06 (47%) -[ 3.097555] statusline: A: 00:00:03 / 00:00:06 (49%) -[ 3.148014] statusline: A: 00:00:03 / 00:00:06 (50%) -[ 3.198297] statusline: A: 00:00:03 / 00:00:06 (51%) -[ 3.297159] statusline: A: 00:00:03 / 00:00:06 (52%) -[ 3.347168] statusline: A: 00:00:03 / 00:00:06 (53%) -[ 3.397600] statusline: A: 00:00:03 / 00:00:06 (54%) -[ 3.447982] statusline: A: 00:00:03 / 00:00:06 (55%) -[ 3.546904] statusline: A: 00:00:03 / 00:00:06 (56%) -[ 3.597173] statusline: A: 00:00:03 / 00:00:06 (57%) -[ 3.647316] statusline: A: 00:00:03 / 00:00:06 (58%) -[ 3.697469] statusline: A: 00:00:03 / 00:00:06 (59%) -[ 3.747611] statusline: A: 00:00:03 / 00:00:06 (60%) -[ 3.847710] statusline: A: 00:00:03 / 00:00:06 (61%) -[ 3.897729] statusline: A: 00:00:03 / 00:00:06 (62%) -[ 3.947727] statusline: A: 00:00:03 / 00:00:06 (63%) -[ 3.997728] statusline: A: 00:00:03 / 00:00:06 (64%) -[ 4.097929] statusline: A: 00:00:04 / 00:00:06 (65%) -[ 4.148442] statusline: A: 00:00:04 / 00:00:06 (66%) -[ 4.247516] statusline: A: 00:00:04 / 00:00:06 (68%) -[ 4.297936] statusline: A: 00:00:04 / 00:00:06 (69%) -[ 4.446775] statusline: A: 00:00:04 / 00:00:06 (71%) -[ 4.497732] statusline: A: 00:00:04 / 00:00:06 (72%) -[ 4.596259] statusline: A: 00:00:04 / 00:00:06 (73%) -[ 4.646437] statusline: A: 00:00:04 / 00:00:06 (74%) -[ 4.696587] statusline: A: 00:00:04 / 00:00:06 (75%) -[ 4.746844] statusline: A: 00:00:04 / 00:00:06 (76%) -[ 4.845918] statusline: A: 00:00:04 / 00:00:06 (78%) -[ 4.995499] statusline: A: 00:00:04 / 00:00:06 (80%) -[ 5.045552] statusline: A: 00:00:04 / 00:00:06 (81%) -[ 5.095580] statusline: A: 00:00:04 / 00:00:06 (82%) -[ 5.145595] statusline: A: 00:00:05 / 00:00:06 (83%) -[ 5.246131] statusline: A: 00:00:05 / 00:00:06 (84%) -[ 5.296284] statusline: A: 00:00:05 / 00:00:06 (85%) -[ 5.394832] statusline: A: 00:00:05 / 00:00:06 (87%) -[ 5.495218] statusline: A: 00:00:05 / 00:00:06 (88%) -[ 5.545694] statusline: A: 00:00:05 / 00:00:06 (89%) -[ 5.644306] statusline: A: 00:00:05 / 00:00:06 (91%) -[ 5.744449] statusline: A: 00:00:05 / 00:00:06 (92%) -[ 5.794842] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 5.844892] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.845275] cplayer: audio filter EOF -[ 5.845280] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.845339] cplayer: audio draining -[ 5.845346] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.845365] cplayer: audio EOF reached -[ 5.845372] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.845386] cplayer: EOF code: 1 -[ 5.845406] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.845837] cplayer: Terminating demuxers... -[ 5.845854] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.846173] cplayer: Done terminating demuxers. -[ 5.846192] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.846273] cplayer: finished playback, success (reason 0) -[ 5.846280] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.846298] cplayer: Exiting... (End of file) -[ 5.846303] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.847886] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] -[ 5.847902] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.849192] mpris: Exiting... -[ 5.849201] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 5.851929] cplayer: draining left over audio -[ 5.851961] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 0.091476] mpris: Loading cplugin script ../mpris.so... +[ 0.114824] cplayer: Waiting for scripts... +[ 0.192268] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] +[ 0.200524] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.200753] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] +[ 0.204460] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.219202] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] +[ 0.219386] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.219508] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] +[ 0.219620] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.221458] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.221754] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.221988] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.222203] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.257004] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] +[ 0.257460] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] +[ 0.259589] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.259833] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.260599] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] +[ 0.261128] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] +[ 0.261354] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] +[ 0.261524] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] +[ 0.261852] cplayer: Set property: user-data/osc/visibility="auto" -> 1 +[ 0.261977] cplayer: Run command: disable-section, flags=64, args=[name="input"] +[ 0.262076] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] +[ 0.262458] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.263814] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.264174] cplayer: Done loading scripts. +[ 0.264868] cplayer: Running hook: ytdl_hook/on_load +[ 0.268181] cplayer: Running hook: ytdl_hook/on_load +[ 0.292300] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.301192] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] +[ 0.411227] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +[ 0.438400] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.438539] cplayer: Running hook: ytdl_hook/on_preloaded +[ 0.438991] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) +[ 0.451093] cplayer: Starting playback... +[ 0.459076] cplayer: AO: [null] 48000Hz stereo 2ch floatp +[ 0.459166] cplayer: AO: Description: Null audio output +[ 0.471175] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.471439] cplayer: audio ready +[ 0.471523] cplayer: starting audio playback +[ 0.471666] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof +[ 0.484348] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] +[ 0.484893] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.485096] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] +[ 0.485237] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.486277] statusline: A: 00:00:00 / 00:00:06 (0%) +[ 0.510892] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.510942] statusline: A: 00:00:00 / 00:00:06 (0%) +[ 0.536528] statusline: A: 00:00:00 / 00:00:06 (1%) +[ 0.586699] statusline: A: 00:00:00 / 00:00:06 (2%) +[ 0.636904] statusline: A: 00:00:00 / 00:00:06 (3%) +[ 0.738122] statusline: A: 00:00:00 / 00:00:06 (4%) +[ 0.788630] statusline: A: 00:00:00 / 00:00:06 (5%) +[ 0.838746] statusline: A: 00:00:00 / 00:00:06 (6%) +[ 0.888994] statusline: A: 00:00:00 / 00:00:06 (7%) +[ 0.939132] statusline: A: 00:00:00 / 00:00:06 (8%) +[ 1.039573] statusline: A: 00:00:00 / 00:00:06 (9%) +[ 1.089753] statusline: A: 00:00:00 / 00:00:06 (10%) +[ 1.139942] statusline: A: 00:00:00 / 00:00:06 (11%) +[ 1.190616] statusline: A: 00:00:00 / 00:00:06 (12%) +[ 1.240876] statusline: A: 00:00:00 / 00:00:06 (13%) +[ 1.374882] statusline: A: 00:00:00 / 00:00:06 (15%) +[ 1.425237] statusline: A: 00:00:00 / 00:00:06 (16%) +[ 1.480343] statusline: A: 00:00:01 / 00:00:06 (16%) +[ 1.531161] statusline: A: 00:00:01 / 00:00:06 (17%) +[ 1.582371] statusline: A: 00:00:01 / 00:00:06 (18%) +[ 1.632570] statusline: A: 00:00:01 / 00:00:06 (19%) +[ 1.695243] statusline: A: 00:00:01 / 00:00:06 (20%) +[ 1.747558] statusline: A: 00:00:01 / 00:00:06 (21%) +[ 1.803925] statusline: A: 00:00:01 / 00:00:06 (22%) +[ 1.854276] statusline: A: 00:00:01 / 00:00:06 (23%) +[ 1.964990] statusline: A: 00:00:01 / 00:00:06 (24%) +[ 2.023810] statusline: A: 00:00:01 / 00:00:06 (25%) +[ 2.080475] statusline: A: 00:00:01 / 00:00:06 (26%) +[ 2.132376] statusline: A: 00:00:01 / 00:00:06 (27%) +[ 2.184358] statusline: A: 00:00:01 / 00:00:06 (28%) +[ 2.244522] statusline: A: 00:00:01 / 00:00:06 (29%) +[ 2.297023] statusline: A: 00:00:01 / 00:00:06 (30%) +[ 2.351072] statusline: A: 00:00:01 / 00:00:06 (31%) +[ 2.451700] statusline: A: 00:00:01 / 00:00:06 (32%) +[ 2.505698] statusline: A: 00:00:02 / 00:00:06 (33%) +[ 2.557479] statusline: A: 00:00:02 / 00:00:06 (34%) +[ 2.610390] statusline: A: 00:00:02 / 00:00:06 (35%) +[ 2.661767] statusline: A: 00:00:02 / 00:00:06 (36%) +[ 2.712402] statusline: A: 00:00:02 / 00:00:06 (37%) +[ 2.824580] statusline: A: 00:00:02 / 00:00:06 (38%) +[ 2.882583] statusline: A: 00:00:02 / 00:00:06 (39%) +[ 2.932975] statusline: A: 00:00:02 / 00:00:06 (40%) +[ 2.984409] statusline: A: 00:00:02 / 00:00:06 (41%) +[ 3.039673] statusline: A: 00:00:02 / 00:00:06 (42%) +[ 3.093229] statusline: A: 00:00:02 / 00:00:06 (43%) +[ 3.146817] statusline: A: 00:00:02 / 00:00:06 (44%) +[ 3.252933] statusline: A: 00:00:02 / 00:00:06 (45%) +[ 3.306037] statusline: A: 00:00:02 / 00:00:06 (46%) +[ 3.358272] statusline: A: 00:00:02 / 00:00:06 (47%) +[ 3.416422] statusline: A: 00:00:02 / 00:00:06 (48%) +[ 3.475278] statusline: A: 00:00:03 / 00:00:06 (49%) +[ 3.532278] statusline: A: 00:00:03 / 00:00:06 (50%) +[ 3.583032] statusline: A: 00:00:03 / 00:00:06 (51%) +[ 3.637744] statusline: A: 00:00:03 / 00:00:06 (52%) +[ 3.703693] statusline: A: 00:00:03 / 00:00:06 (53%) +[ 3.754966] statusline: A: 00:00:03 / 00:00:06 (54%) +[ 3.864823] statusline: A: 00:00:03 / 00:00:06 (55%) +[ 3.916521] statusline: A: 00:00:03 / 00:00:06 (56%) +[ 3.967224] statusline: A: 00:00:03 / 00:00:06 (57%) +[ 4.018608] statusline: A: 00:00:03 / 00:00:06 (58%) +[ 4.071717] statusline: A: 00:00:03 / 00:00:06 (59%) +[ 4.122424] statusline: A: 00:00:03 / 00:00:06 (60%) +[ 4.232762] statusline: A: 00:00:03 / 00:00:06 (61%) +[ 4.286504] statusline: A: 00:00:03 / 00:00:06 (62%) +[ 4.336862] statusline: A: 00:00:03 / 00:00:06 (63%) +[ 4.387231] statusline: A: 00:00:03 / 00:00:06 (64%) +[ 4.445284] statusline: A: 00:00:03 / 00:00:06 (65%) +[ 4.496221] statusline: A: 00:00:04 / 00:00:06 (66%) +[ 4.598807] statusline: A: 00:00:04 / 00:00:06 (67%) +[ 4.649112] statusline: A: 00:00:04 / 00:00:06 (68%) +[ 4.707649] statusline: A: 00:00:04 / 00:00:06 (69%) +[ 4.764236] statusline: A: 00:00:04 / 00:00:06 (70%) +[ 4.828228] statusline: A: 00:00:04 / 00:00:06 (71%) +[ 4.880283] statusline: A: 00:00:04 / 00:00:06 (72%) +[ 4.930842] statusline: A: 00:00:04 / 00:00:06 (73%) +[ 4.992732] statusline: A: 00:00:04 / 00:00:06 (74%) +[ 5.045266] statusline: A: 00:00:04 / 00:00:06 (75%) +[ 5.148755] statusline: A: 00:00:04 / 00:00:06 (76%) +[ 5.199049] statusline: A: 00:00:04 / 00:00:06 (77%) +[ 5.252261] statusline: A: 00:00:04 / 00:00:06 (78%) +[ 5.307301] statusline: A: 00:00:04 / 00:00:06 (79%) +[ 5.359008] statusline: A: 00:00:04 / 00:00:06 (80%) +[ 5.409240] statusline: A: 00:00:04 / 00:00:06 (81%) +[ 5.515405] statusline: A: 00:00:05 / 00:00:06 (82%) +[ 5.567718] statusline: A: 00:00:05 / 00:00:06 (83%) +[ 5.621134] statusline: A: 00:00:05 / 00:00:06 (84%) +[ 5.676254] statusline: A: 00:00:05 / 00:00:06 (85%) +[ 5.730082] statusline: A: 00:00:05 / 00:00:06 (86%) +[ 5.784010] statusline: A: 00:00:05 / 00:00:06 (87%) +[ 5.837282] statusline: A: 00:00:05 / 00:00:06 (88%) +[ 5.940577] statusline: A: 00:00:05 / 00:00:06 (89%) +[ 5.991183] statusline: A: 00:00:05 / 00:00:06 (90%) +[ 6.043693] statusline: A: 00:00:05 / 00:00:06 (91%) +[ 6.097785] statusline: A: 00:00:05 / 00:00:06 (92%) +[ 6.152380] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 6.207014] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.210727] cplayer: audio filter EOF +[ 6.210740] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.211012] cplayer: audio draining +[ 6.211053] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.211096] cplayer: audio EOF reached +[ 6.211117] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.211175] cplayer: EOF code: 1 +[ 6.211200] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.212586] cplayer: Terminating demuxers... +[ 6.212638] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.216651] cplayer: Done terminating demuxers. +[ 6.216682] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.216877] cplayer: finished playback, success (reason 0) +[ 6.216906] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.216947] cplayer: Exiting... (End of file) +[ 6.216982] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.228680] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] +[ 6.228720] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.242964] mpris: Exiting... +[ 6.243056] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 6.265878] cplayer: draining left over audio +[ 6.265980] statusline: A: 00:00:05 / 00:00:06 (94%) make[2]: Leaving directory '/build/reproducible-path/mpv-mpris-0.7.1/test' make[2]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1/test' ./wrapper "pause" @@ -2756,8 +2813,8 @@ + MPV_MPRIS_TEST_DBUS=./dbus + mkdir -p -m 0700 ./dbus + export -n MPV_MPRIS_TEST_DBUS MPV_MPRIS_TEST_XAUTH MPV_MPRIS_TEST_TMP -+ grep -- --auto-display + xvfb-run --help ++ grep -- --auto-display + xvfb_auto=--auto-servernum + rm -f ./pause.xvfb.log ./pause.Xauthority + exec env MPV_MPRIS_TEST_NAME=pause XDG_RUNTIME_DIR=./dbus TEMPDIR=. TMPDIR=. TEMP=. TMP=. dbus-run-session -- xvfb-run --auto-servernum --error-file ./pause.xvfb.log -f ./pause.Xauthority ./pause @@ -2778,20 +2835,32 @@ ++ '[' -n ../mpris.so ']' ++ params+=("--load-scripts=no" "--script=$MPV_MPRIS_TEST_PLUGIN") ++ unset MPV_MPRIS_TEST_PLUGIN MPV_MPRIS_TEST_PLAY MPV_MPRIS_TEST_MPV_IPC MPV_MPRIS_TEST_LOG -++ echo DISPLAY=:99 -DISPLAY=:99 -++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-ICzbKQIMHl,guid=39c809f15a5d7dd1fb6834ef662bff69 -DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-ICzbKQIMHl,guid=39c809f15a5d7dd1fb6834ef662bff69 +++ echo DISPLAY=:102 +DISPLAY=:102 +++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-CI9NtmUTid,guid=084d2528056beac025a39e21662c018a +DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-CI9NtmUTid,guid=084d2528056beac025a39e21662c018a ++ rm -f ./pause.mpv.ipc ./pause.mpv.log ./pause.socat.log ++ wait_for test -S ./pause.mpv.ipc ++ timeout=0 ++ test -S ./pause.mpv.ipc -++ mpv --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./pause.mpv.log --input-ipc-server=./pause.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga ++ '[' 0 -eq 60 ']' ++ sleep 1 +++ mpv --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./pause.mpv.log --input-ipc-server=./pause.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +++ awk '/statusline.*Paused/{print>"/dev/stdout";next} 1' ++ timeout=1 ++ test -S ./pause.mpv.ipc +++ '[' 1 -eq 60 ']' +++ sleep 1 +++ timeout=2 +++ test -S ./pause.mpv.ipc +++ '[' 2 -eq 60 ']' +++ sleep 1 +++ timeout=3 +++ test -S ./pause.mpv.ipc +++ '[' 3 -eq 60 ']' +++ sleep 1 +++ timeout=4 +++ test -S ./pause.mpv.ipc ++ wait_for playerctl_list_all_is_mpv ++ timeout=0 ++ playerctl_list_all_is_mpv @@ -2880,165 +2949,176 @@ [cplayer] Setting option 'msg-level' = 'cplayer=trace,mpris=trace' (flags = 8) [cplayer] Setting option 'log-file' = './pause.mpv.log' (flags = 8) [cplayer] Setting option 'input-ipc-server' = './pause.mpv.ipc' (flags = 8) -[ 0.039891] mpris: Loading cplugin script ../mpris.so... -[ 0.040392] cplayer: Waiting for scripts... -[ 0.059432] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] -[ 0.060704] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.060792] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] -[ 0.061544] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.063369] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] -[ 0.063561] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] -[ 0.063628] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.063656] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.063802] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] -[ 0.063949] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] -[ 0.063991] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] -[ 0.064047] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] -[ 0.064125] cplayer: Set property: user-data/osc/visibility="auto" -> 1 -[ 0.064156] cplayer: Run command: disable-section, flags=64, args=[name="input"] -[ 0.064178] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] -[ 0.064244] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.064773] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] -[ 0.064866] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.064911] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] -[ 0.064972] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.065045] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.065116] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.065176] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.065221] cplayer: Done loading scripts. -[ 0.065479] cplayer: Running hook: ytdl_hook/on_load -[ 0.066578] cplayer: Running hook: ytdl_hook/on_load -[ 0.066881] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.067220] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.070632] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.071305] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] -[ 0.079927] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga -[ 0.090901] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] -[ 0.090994] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.091049] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] -[ 0.091087] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.091166] cplayer: Running hook: ytdl_hook/on_preloaded -[ 0.091455] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) -[ 0.094420] cplayer: Starting playback... -[ 0.096138] cplayer: AO: [null] 48000Hz stereo 2ch floatp -[ 0.096179] cplayer: AO: Description: Null audio output -[ 0.098123] cplayer: audio ready -[ 0.098189] cplayer: starting audio playback -[ 0.098261] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof -[ 0.117206] statusline: A: 00:00:00 / 00:00:06 (0%) -[ 0.122807] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.122828] statusline: A: 00:00:00 / 00:00:06 (0%) -[ 0.167367] statusline: A: 00:00:00 / 00:00:06 (1%) -[ 0.217503] statusline: A: 00:00:00 / 00:00:06 (2%) -[ 0.267634] statusline: A: 00:00:00 / 00:00:06 (3%) -[ 0.317782] statusline: A: 00:00:00 / 00:00:06 (4%) -[ 0.418069] statusline: A: 00:00:00 / 00:00:06 (5%) -[ 0.468213] statusline: A: 00:00:00 / 00:00:06 (6%) -[ 0.518381] statusline: A: 00:00:00 / 00:00:06 (7%) -[ 0.568496] statusline: A: 00:00:00 / 00:00:06 (8%) -[ 0.668776] statusline: A: 00:00:00 / 00:00:06 (9%) -[ 0.718935] statusline: A: 00:00:00 / 00:00:06 (10%) -[ 0.769059] statusline: A: 00:00:00 / 00:00:06 (11%) -[ 0.819190] statusline: A: 00:00:00 / 00:00:06 (12%) -[ 0.869321] statusline: A: 00:00:00 / 00:00:06 (13%) -[ 0.969608] statusline: A: 00:00:00 / 00:00:06 (14%) -[ 1.019735] statusline: A: 00:00:00 / 00:00:06 (15%) -[ 1.069874] statusline: A: 00:00:00 / 00:00:06 (16%) -[ 1.145781] statusline: A: 00:00:01 / 00:00:06 (17%) -[ 1.196384] statusline: A: 00:00:01 / 00:00:06 (18%) -[ 1.246723] statusline: A: 00:00:01 / 00:00:06 (19%) -[ 1.296925] statusline: A: 00:00:01 / 00:00:06 (20%) -[ 1.395255] statusline: A: 00:00:01 / 00:00:06 (21%) -[ 1.445555] statusline: A: 00:00:01 / 00:00:06 (22%) -[ 1.544810] statusline: A: 00:00:01 / 00:00:06 (24%) -[ 1.645411] statusline: A: 00:00:01 / 00:00:06 (25%) -[ 1.695784] statusline: A: 00:00:01 / 00:00:06 (26%) -[ 1.795170] statusline: A: 00:00:01 / 00:00:06 (28%) -[ 1.845213] statusline: A: 00:00:01 / 00:00:06 (29%) -[ 1.945912] statusline: A: 00:00:01 / 00:00:06 (30%) -[ 2.045612] statusline: A: 00:00:01 / 00:00:06 (32%) -[ 2.095682] statusline: A: 00:00:01 / 00:00:06 (33%) -[ 2.145798] statusline: A: 00:00:02 / 00:00:06 (33%) -[ 2.195883] statusline: A: 00:00:02 / 00:00:06 (34%) -[ 2.246597] statusline: A: 00:00:02 / 00:00:06 (35%) -[ 2.297178] statusline: A: 00:00:02 / 00:00:06 (36%) -[ 2.396005] statusline: A: 00:00:02 / 00:00:06 (37%) -[ 2.446094] statusline: A: 00:00:02 / 00:00:06 (38%) -[ 2.496517] statusline: A: 00:00:02 / 00:00:06 (39%) -[ 2.546762] statusline: A: 00:00:02 / 00:00:06 (40%) -[ 2.646031] statusline: A: 00:00:02 / 00:00:06 (42%) -[ 2.746203] statusline: A: 00:00:02 / 00:00:06 (43%) -[ 2.796260] statusline: A: 00:00:02 / 00:00:06 (44%) -[ 2.810401] cplayer: Set property: pause=true -> 1 -[ 2.810425] statusline: A: 00:00:02 / 00:00:06 (44%) -[ 2.810642] statusline: (Paused) A: 00:00:02 / 00:00:06 (44%) -[ 3.890273] cplayer: Set property: pause=false -> 1 -[ 3.890299] statusline: (Paused) A: 00:00:02 / 00:00:06 (44%) -[ 3.890505] statusline: A: 00:00:02 / 00:00:06 (44%) -[ 3.940655] statusline: A: 00:00:02 / 00:00:06 (45%) -[ 3.990841] statusline: A: 00:00:02 / 00:00:06 (46%) -[ 4.040880] statusline: A: 00:00:02 / 00:00:06 (47%) -[ 4.090985] statusline: A: 00:00:02 / 00:00:06 (48%) -[ 4.191285] statusline: A: 00:00:03 / 00:00:06 (49%) -[ 4.241455] statusline: A: 00:00:03 / 00:00:06 (50%) -[ 4.340250] statusline: A: 00:00:03 / 00:00:06 (52%) -[ 4.489900] statusline: A: 00:00:03 / 00:00:06 (54%) -[ 4.540682] statusline: A: 00:00:03 / 00:00:06 (55%) -[ 4.639423] statusline: A: 00:00:03 / 00:00:06 (56%) -[ 4.689892] statusline: A: 00:00:03 / 00:00:06 (57%) -[ 4.740072] statusline: A: 00:00:03 / 00:00:06 (58%) -[ 4.790099] statusline: A: 00:00:03 / 00:00:06 (59%) -[ 4.840122] statusline: A: 00:00:03 / 00:00:06 (60%) -[ 4.890129] statusline: A: 00:00:03 / 00:00:06 (61%) -[ 5.039866] statusline: A: 00:00:03 / 00:00:06 (63%) -[ 5.089859] statusline: A: 00:00:03 / 00:00:06 (64%) -[ 5.139858] statusline: A: 00:00:03 / 00:00:06 (65%) -[ 5.190533] statusline: A: 00:00:04 / 00:00:06 (65%) -[ 5.289400] statusline: A: 00:00:04 / 00:00:06 (67%) -[ 5.340219] statusline: A: 00:00:04 / 00:00:06 (68%) -[ 5.390382] statusline: A: 00:00:04 / 00:00:06 (69%) -[ 5.488720] statusline: A: 00:00:04 / 00:00:06 (70%) -[ 5.539844] statusline: A: 00:00:04 / 00:00:06 (71%) -[ 5.638876] statusline: A: 00:00:04 / 00:00:06 (73%) -[ 5.689036] statusline: A: 00:00:04 / 00:00:06 (74%) -[ 5.789186] statusline: A: 00:00:04 / 00:00:06 (75%) -[ 5.839224] statusline: A: 00:00:04 / 00:00:06 (76%) -[ 5.889783] statusline: A: 00:00:04 / 00:00:06 (77%) -[ 5.988934] statusline: A: 00:00:04 / 00:00:06 (79%) -[ 6.089300] statusline: A: 00:00:04 / 00:00:06 (80%) -[ 6.188609] statusline: A: 00:00:05 / 00:00:06 (82%) -[ 6.238948] statusline: A: 00:00:05 / 00:00:06 (83%) -[ 6.338389] statusline: A: 00:00:05 / 00:00:06 (84%) -[ 6.388380] statusline: A: 00:00:05 / 00:00:06 (85%) -[ 6.439279] statusline: A: 00:00:05 / 00:00:06 (86%) -[ 6.538253] statusline: A: 00:00:05 / 00:00:06 (87%) -[ 6.588367] statusline: A: 00:00:05 / 00:00:06 (88%) -[ 6.638629] statusline: A: 00:00:05 / 00:00:06 (89%) -[ 6.688681] statusline: A: 00:00:05 / 00:00:06 (90%) -[ 6.788060] statusline: A: 00:00:05 / 00:00:06 (92%) -[ 6.888410] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.938134] cplayer: audio filter EOF -[ 6.938140] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.938192] cplayer: audio draining -[ 6.938199] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.938208] cplayer: audio EOF reached -[ 6.938213] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.938239] cplayer: EOF code: 1 -[ 6.938245] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.938637] cplayer: Terminating demuxers... -[ 6.938654] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.938959] cplayer: Done terminating demuxers. -[ 6.938988] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.939066] cplayer: finished playback, success (reason 0) -[ 6.939078] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.939108] cplayer: Exiting... (End of file) -[ 6.939117] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.940623] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] -[ 6.940647] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.945289] mpris: Exiting... -[ 6.945325] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 6.946507] cplayer: draining left over audio -[ 6.946537] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 0.134897] mpris: Loading cplugin script ../mpris.so... +[ 0.135568] cplayer: Waiting for scripts... +[ 0.252245] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.252540] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.252760] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.252959] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.258876] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] +[ 0.271952] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.272216] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] +[ 0.272336] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.276458] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] +[ 0.276890] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] +[ 0.277101] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.277208] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.281718] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] +[ 0.282256] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] +[ 0.282410] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] +[ 0.282620] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] +[ 0.282838] cplayer: Set property: user-data/osc/visibility="auto" -> 1 +[ 0.282911] cplayer: Run command: disable-section, flags=64, args=[name="input"] +[ 0.282977] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] +[ 0.295258] cplayer: Set property: user-data/osc/margins={"r":0,"b":0,"l":0,"t":0} -> 1 +[ 0.304546] cplayer: Done loading scripts. +[ 0.305291] cplayer: Running hook: ytdl_hook/on_load +[ 0.307504] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.307934] cplayer: Running hook: ytdl_hook/on_load +[ 0.308209] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] +[ 0.308327] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.308444] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] +[ 0.308594] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.310378] cplayer: Set property: user-data/osc/margins={"r":0,"b":0,"l":0,"t":0} -> 1 +[ 0.326142] cplayer: Set property: user-data/osc/margins={"r":0,"b":0,"l":0,"t":0} -> 1 +[ 0.332436] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] +[ 0.432206] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] +[ 0.432449] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.432625] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] +[ 0.432778] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.464402] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +[ 0.468707] cplayer: Running hook: ytdl_hook/on_preloaded +[ 0.469308] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) +[ 0.494512] cplayer: Starting playback... +[ 0.509538] cplayer: AO: [null] 48000Hz stereo 2ch floatp +[ 0.509709] cplayer: AO: Description: Null audio output +[ 0.539230] cplayer: audio ready +[ 0.539362] cplayer: starting audio playback +[ 0.539479] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof +[ 0.543486] cplayer: Set property: user-data/osc/margins={"r":0,"b":0,"l":0,"t":0} -> 1 +[ 0.546664] statusline: A: 00:00:00 / 00:00:06 (0%) +[ 0.580572] cplayer: Set property: user-data/osc/margins={"r":0,"b":0,"l":0,"t":0} -> 1 +[ 0.580612] statusline: A: 00:00:00 / 00:00:06 (0%) +[ 0.599447] statusline: A: 00:00:00 / 00:00:06 (1%) +[ 0.649584] statusline: A: 00:00:00 / 00:00:06 (2%) +[ 0.699893] statusline: A: 00:00:00 / 00:00:06 (3%) +[ 0.801209] statusline: A: 00:00:00 / 00:00:06 (4%) +[ 0.856714] statusline: A: 00:00:00 / 00:00:06 (5%) +[ 0.906929] statusline: A: 00:00:00 / 00:00:06 (6%) +[ 0.957115] statusline: A: 00:00:00 / 00:00:06 (7%) +[ 1.007461] statusline: A: 00:00:00 / 00:00:06 (8%) +[ 1.108127] statusline: A: 00:00:00 / 00:00:06 (9%) +[ 1.158274] statusline: A: 00:00:00 / 00:00:06 (10%) +[ 1.208581] statusline: A: 00:00:00 / 00:00:06 (11%) +[ 1.258740] statusline: A: 00:00:00 / 00:00:06 (12%) +[ 1.359386] statusline: A: 00:00:00 / 00:00:06 (13%) +[ 1.444129] statusline: A: 00:00:00 / 00:00:06 (15%) +[ 1.495720] statusline: A: 00:00:00 / 00:00:06 (16%) +[ 1.546604] statusline: A: 00:00:01 / 00:00:06 (16%) +[ 1.597301] statusline: A: 00:00:01 / 00:00:06 (17%) +[ 1.647606] statusline: A: 00:00:01 / 00:00:06 (18%) +[ 1.703301] statusline: A: 00:00:01 / 00:00:06 (19%) +[ 1.753964] statusline: A: 00:00:01 / 00:00:06 (20%) +[ 1.807586] statusline: A: 00:00:01 / 00:00:06 (21%) +[ 1.911393] statusline: A: 00:00:01 / 00:00:06 (22%) +[ 1.962870] statusline: A: 00:00:01 / 00:00:06 (23%) +[ 2.014609] statusline: A: 00:00:01 / 00:00:06 (24%) +[ 2.075127] statusline: A: 00:00:01 / 00:00:06 (25%) +[ 2.127380] statusline: A: 00:00:01 / 00:00:06 (26%) +[ 2.177753] statusline: A: 00:00:01 / 00:00:06 (27%) +[ 2.239474] statusline: A: 00:00:01 / 00:00:06 (28%) +[ 2.299770] statusline: A: 00:00:01 / 00:00:06 (29%) +[ 2.354862] statusline: A: 00:00:01 / 00:00:06 (30%) +[ 2.462725] statusline: A: 00:00:01 / 00:00:06 (31%) +[ 2.513576] statusline: A: 00:00:01 / 00:00:06 (32%) +[ 2.563983] statusline: A: 00:00:02 / 00:00:06 (33%) +[ 2.615554] statusline: A: 00:00:02 / 00:00:06 (34%) +[ 2.666256] statusline: A: 00:00:02 / 00:00:06 (35%) +[ 2.779838] statusline: A: 00:00:02 / 00:00:06 (36%) +[ 2.838134] statusline: A: 00:00:02 / 00:00:06 (37%) +[ 2.895912] statusline: A: 00:00:02 / 00:00:06 (38%) +[ 2.947845] statusline: A: 00:00:02 / 00:00:06 (39%) +[ 2.999340] statusline: A: 00:00:02 / 00:00:06 (40%) +[ 3.059345] statusline: A: 00:00:02 / 00:00:06 (41%) +[ 3.114667] statusline: A: 00:00:02 / 00:00:06 (42%) +[ 3.153019] cplayer: Set property: pause=true -> 1 +[ 3.153088] statusline: A: 00:00:02 / 00:00:06 (42%) +[ 3.153688] statusline: (Paused) A: 00:00:02 / 00:00:06 (43%) +[ 4.444912] cplayer: Set property: pause=false -> 1 +[ 4.444953] statusline: (Paused) A: 00:00:02 / 00:00:06 (43%) +[ 4.445329] statusline: A: 00:00:02 / 00:00:06 (43%) +[ 4.505016] statusline: A: 00:00:02 / 00:00:06 (44%) +[ 4.609794] statusline: A: 00:00:02 / 00:00:06 (45%) +[ 4.666564] statusline: A: 00:00:02 / 00:00:06 (46%) +[ 4.719545] statusline: A: 00:00:02 / 00:00:06 (47%) +[ 4.771481] statusline: A: 00:00:02 / 00:00:06 (48%) +[ 4.821886] statusline: A: 00:00:02 / 00:00:06 (49%) +[ 4.879073] statusline: A: 00:00:03 / 00:00:06 (50%) +[ 4.930227] statusline: A: 00:00:03 / 00:00:06 (51%) +[ 5.043107] statusline: A: 00:00:03 / 00:00:06 (52%) +[ 5.094115] statusline: A: 00:00:03 / 00:00:06 (53%) +[ 5.144594] statusline: A: 00:00:03 / 00:00:06 (54%) +[ 5.199261] statusline: A: 00:00:03 / 00:00:06 (55%) +[ 5.249901] statusline: A: 00:00:03 / 00:00:06 (56%) +[ 5.300901] statusline: A: 00:00:03 / 00:00:06 (57%) +[ 5.409690] statusline: A: 00:00:03 / 00:00:06 (58%) +[ 5.460876] statusline: A: 00:00:03 / 00:00:06 (59%) +[ 5.515223] statusline: A: 00:00:03 / 00:00:06 (60%) +[ 5.565536] statusline: A: 00:00:03 / 00:00:06 (61%) +[ 5.621072] statusline: A: 00:00:03 / 00:00:06 (62%) +[ 5.673139] statusline: A: 00:00:03 / 00:00:06 (63%) +[ 5.777818] statusline: A: 00:00:03 / 00:00:06 (64%) +[ 5.830260] statusline: A: 00:00:03 / 00:00:06 (65%) +[ 5.882431] statusline: A: 00:00:04 / 00:00:06 (66%) +[ 5.932986] statusline: A: 00:00:04 / 00:00:06 (67%) +[ 5.983276] statusline: A: 00:00:04 / 00:00:06 (68%) +[ 6.043412] statusline: A: 00:00:04 / 00:00:06 (69%) +[ 6.097660] statusline: A: 00:00:04 / 00:00:06 (70%) +[ 6.205507] statusline: A: 00:00:04 / 00:00:06 (71%) +[ 6.260968] statusline: A: 00:00:04 / 00:00:06 (72%) +[ 6.315111] statusline: A: 00:00:04 / 00:00:06 (73%) +[ 6.369316] statusline: A: 00:00:04 / 00:00:06 (74%) +[ 6.423337] statusline: A: 00:00:04 / 00:00:06 (75%) +[ 6.474674] statusline: A: 00:00:04 / 00:00:06 (76%) +[ 6.525018] statusline: A: 00:00:04 / 00:00:06 (77%) +[ 6.630221] statusline: A: 00:00:04 / 00:00:06 (78%) +[ 6.682834] statusline: A: 00:00:04 / 00:00:06 (79%) +[ 6.734246] statusline: A: 00:00:04 / 00:00:06 (80%) +[ 6.786452] statusline: A: 00:00:04 / 00:00:06 (81%) +[ 6.839370] statusline: A: 00:00:05 / 00:00:06 (82%) +[ 6.899655] statusline: A: 00:00:05 / 00:00:06 (83%) +[ 6.957951] statusline: A: 00:00:05 / 00:00:06 (84%) +[ 7.065426] statusline: A: 00:00:05 / 00:00:06 (85%) +[ 7.116996] statusline: A: 00:00:05 / 00:00:06 (86%) +[ 7.174698] statusline: A: 00:00:05 / 00:00:06 (87%) +[ 7.226859] statusline: A: 00:00:05 / 00:00:06 (88%) +[ 7.282133] statusline: A: 00:00:05 / 00:00:06 (89%) +[ 7.334602] statusline: A: 00:00:05 / 00:00:06 (90%) +[ 7.388897] statusline: A: 00:00:05 / 00:00:06 (91%) +[ 7.493352] statusline: A: 00:00:05 / 00:00:06 (92%) +[ 7.547860] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.552664] cplayer: audio filter EOF +[ 7.552676] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.552840] cplayer: audio draining +[ 7.552870] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.552902] cplayer: audio EOF reached +[ 7.552938] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.552998] cplayer: EOF code: 1 +[ 7.553032] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.554242] cplayer: Terminating demuxers... +[ 7.554277] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.566899] cplayer: Done terminating demuxers. +[ 7.566958] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.567233] cplayer: finished playback, success (reason 0) +[ 7.567254] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.567294] cplayer: Exiting... (End of file) +[ 7.567313] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.569366] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] +[ 7.569379] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.591419] mpris: Exiting... +[ 7.591462] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 7.594295] cplayer: draining left over audio +[ 7.594335] statusline: A: 00:00:05 / 00:00:06 (93%) make[2]: Leaving directory '/build/reproducible-path/mpv-mpris-0.7.1/test' make[2]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1/test' ./wrapper "play" @@ -3100,8 +3180,8 @@ + MPV_MPRIS_TEST_DBUS=./dbus + mkdir -p -m 0700 ./dbus + export -n MPV_MPRIS_TEST_DBUS MPV_MPRIS_TEST_XAUTH MPV_MPRIS_TEST_TMP -+ xvfb-run --help + grep -- --auto-display ++ xvfb-run --help + xvfb_auto=--auto-servernum + rm -f ./play.xvfb.log ./play.Xauthority + exec env MPV_MPRIS_TEST_NAME=play XDG_RUNTIME_DIR=./dbus TEMPDIR=. TMPDIR=. TEMP=. TMP=. dbus-run-session -- xvfb-run --auto-servernum --error-file ./play.xvfb.log -f ./play.Xauthority ./play @@ -3124,20 +3204,28 @@ ++ '[' -n ../mpris.so ']' ++ params+=("--load-scripts=no" "--script=$MPV_MPRIS_TEST_PLUGIN") ++ unset MPV_MPRIS_TEST_PLUGIN MPV_MPRIS_TEST_PLAY MPV_MPRIS_TEST_MPV_IPC MPV_MPRIS_TEST_LOG -++ echo DISPLAY=:101 -DISPLAY=:101 -++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-tvh17Tp22r,guid=f1ae4f65886c231649ca1bbc662bff69 -DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-tvh17Tp22r,guid=f1ae4f65886c231649ca1bbc662bff69 +++ echo DISPLAY=:100 +DISPLAY=:100 +++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-icutsLUBP4,guid=20639f9cb3a63b668f12f22d662c018a +DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-icutsLUBP4,guid=20639f9cb3a63b668f12f22d662c018a ++ rm -f ./play.mpv.ipc ./play.mpv.log ./play.socat.log ++ wait_for test -S ./play.mpv.ipc ++ timeout=0 ++ test -S ./play.mpv.ipc -++ mpv --pause --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./play.mpv.log --input-ipc-server=./play.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga ++ '[' 0 -eq 60 ']' ++ sleep 1 +++ mpv --pause --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./play.mpv.log --input-ipc-server=./play.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +++ awk '/statusline.*Paused/{print>"/dev/stdout";next} 1' ++ timeout=1 ++ test -S ./play.mpv.ipc +++ '[' 1 -eq 60 ']' +++ sleep 1 +++ timeout=2 +++ test -S ./play.mpv.ipc +++ '[' 2 -eq 60 ']' +++ sleep 1 +++ timeout=3 +++ test -S ./play.mpv.ipc ++ wait_for playerctl_list_all_is_mpv ++ timeout=0 ++ playerctl_list_all_is_mpv @@ -3146,8 +3234,8 @@ ++ test mpv = mpv ++ sleep 2 + status Paused -+ playerctl status + grep '^Paused$' ++ playerctl status Paused + check pause true + prop pause @@ -3225,170 +3313,185 @@ [cplayer] Setting option 'msg-level' = 'cplayer=trace,mpris=trace' (flags = 8) [cplayer] Setting option 'log-file' = './play.mpv.log' (flags = 8) [cplayer] Setting option 'input-ipc-server' = './play.mpv.ipc' (flags = 8) -[ 0.026084] mpris: Loading cplugin script ../mpris.so... -[ 0.026265] cplayer: Waiting for scripts... -[ 0.043542] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] -[ 0.043640] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.044898] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] -[ 0.044969] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.045052] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] -[ 0.045277] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.045390] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] -[ 0.045597] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.045664] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.045728] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.045803] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] -[ 0.046182] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.046281] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.046475] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] -[ 0.047044] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] -[ 0.047244] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] -[ 0.047346] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] -[ 0.047471] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.047598] cplayer: Set property: user-data/osc/visibility="auto" -> 1 -[ 0.047672] cplayer: Run command: disable-section, flags=64, args=[name="input"] -[ 0.047699] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] -[ 0.047821] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 -[ 0.048306] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] -[ 0.048500] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.048629] cplayer: Done loading scripts. -[ 0.048876] cplayer: Running hook: ytdl_hook/on_load -[ 0.049772] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.049906] cplayer: Running hook: ytdl_hook/on_load -[ 0.058838] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 -[ 0.059294] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] -[ 0.062096] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga -[ 0.078044] cplayer: Running hook: ytdl_hook/on_preloaded -[ 0.078350] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) -[ 0.081723] cplayer: Starting playback... -[ 0.082981] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 -[ 0.084126] cplayer: AO: [null] 48000Hz stereo 2ch floatp -[ 0.084168] cplayer: AO: Description: Null audio output -[ 0.086052] cplayer: audio ready -[ 0.086111] cplayer: starting audio playback -[ 0.086197] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof (paused) -[ 0.088044] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 -[ 0.088365] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] -[ 0.088461] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.088596] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] -[ 0.088681] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.100601] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 0.120378] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 -[ 0.120396] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.798422] cplayer: Set property: pause=false -> 1 -[ 2.798447] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.798759] statusline: A: 00:00:00 / 00:00:06 (0%) -[ 2.849572] statusline: A: 00:00:00 / 00:00:06 (1%) -[ 2.899675] statusline: A: 00:00:00 / 00:00:06 (2%) -[ 3.048178] statusline: A: 00:00:00 / 00:00:06 (4%) -[ 3.098248] statusline: A: 00:00:00 / 00:00:06 (5%) -[ 3.148249] statusline: A: 00:00:00 / 00:00:06 (6%) -[ 3.198636] statusline: A: 00:00:00 / 00:00:06 (7%) -[ 3.297949] statusline: A: 00:00:00 / 00:00:06 (8%) -[ 3.348352] statusline: A: 00:00:00 / 00:00:06 (9%) -[ 3.447652] statusline: A: 00:00:00 / 00:00:06 (11%) -[ 3.547945] statusline: A: 00:00:00 / 00:00:06 (12%) -[ 3.598202] statusline: A: 00:00:00 / 00:00:06 (13%) -[ 3.648482] statusline: A: 00:00:00 / 00:00:06 (14%) -[ 3.698721] statusline: A: 00:00:00 / 00:00:06 (15%) -[ 3.748971] statusline: A: 00:00:00 / 00:00:06 (16%) -[ 3.798960] statusline: A: 00:00:01 / 00:00:06 (16%) -[ 3.848989] statusline: A: 00:00:01 / 00:00:06 (17%) -[ 3.899085] statusline: A: 00:00:01 / 00:00:06 (18%) -[ 3.949083] statusline: A: 00:00:01 / 00:00:06 (19%) -[ 3.999105] statusline: A: 00:00:01 / 00:00:06 (20%) -[ 4.100141] statusline: A: 00:00:01 / 00:00:06 (21%) -[ 4.198808] statusline: A: 00:00:01 / 00:00:06 (23%) -[ 4.248920] statusline: A: 00:00:01 / 00:00:06 (24%) -[ 4.349105] statusline: A: 00:00:01 / 00:00:06 (25%) -[ 4.399162] statusline: A: 00:00:01 / 00:00:06 (26%) -[ 4.449200] statusline: A: 00:00:01 / 00:00:06 (27%) -[ 4.499224] statusline: A: 00:00:01 / 00:00:06 (28%) -[ 4.549729] statusline: A: 00:00:01 / 00:00:06 (29%) -[ 4.649046] statusline: A: 00:00:01 / 00:00:06 (30%) -[ 4.699094] statusline: A: 00:00:01 / 00:00:06 (31%) -[ 4.750143] statusline: A: 00:00:01 / 00:00:06 (32%) -[ 4.800654] statusline: A: 00:00:02 / 00:00:06 (33%) -[ 4.899799] statusline: A: 00:00:02 / 00:00:06 (34%) -[ 4.950787] statusline: A: 00:00:02 / 00:00:06 (35%) -[ 5.000789] statusline: A: 00:00:02 / 00:00:06 (36%) -[ 5.051216] statusline: A: 00:00:02 / 00:00:06 (37%) -[ 5.101628] statusline: A: 00:00:02 / 00:00:06 (38%) -[ 5.200271] statusline: A: 00:00:02 / 00:00:06 (39%) -[ 5.250579] statusline: A: 00:00:02 / 00:00:06 (40%) -[ 5.350051] statusline: A: 00:00:02 / 00:00:06 (42%) -[ 5.450160] statusline: A: 00:00:02 / 00:00:06 (43%) -[ 5.500239] statusline: A: 00:00:02 / 00:00:06 (44%) -[ 5.550286] statusline: A: 00:00:02 / 00:00:06 (45%) -[ 5.600306] statusline: A: 00:00:02 / 00:00:06 (46%) -[ 5.650868] statusline: A: 00:00:02 / 00:00:06 (47%) -[ 5.750025] statusline: A: 00:00:02 / 00:00:06 (48%) -[ 5.800032] statusline: A: 00:00:03 / 00:00:06 (49%) -[ 5.850366] statusline: A: 00:00:03 / 00:00:06 (50%) -[ 5.900669] statusline: A: 00:00:03 / 00:00:06 (51%) -[ 5.999534] statusline: A: 00:00:03 / 00:00:06 (52%) -[ 6.049940] statusline: A: 00:00:03 / 00:00:06 (53%) -[ 6.100466] statusline: A: 00:00:03 / 00:00:06 (54%) -[ 6.198960] statusline: A: 00:00:03 / 00:00:06 (55%) -[ 6.249265] statusline: A: 00:00:03 / 00:00:06 (56%) -[ 6.348522] statusline: A: 00:00:03 / 00:00:06 (58%) -[ 6.398940] statusline: A: 00:00:03 / 00:00:06 (59%) -[ 6.498246] statusline: A: 00:00:03 / 00:00:06 (60%) -[ 6.548800] statusline: A: 00:00:03 / 00:00:06 (61%) -[ 6.647917] statusline: A: 00:00:03 / 00:00:06 (63%) -[ 6.697934] statusline: A: 00:00:03 / 00:00:06 (64%) -[ 6.847371] statusline: A: 00:00:04 / 00:00:06 (66%) -[ 6.897986] statusline: A: 00:00:04 / 00:00:06 (67%) -[ 6.996932] statusline: A: 00:00:04 / 00:00:06 (69%) -[ 7.146536] statusline: A: 00:00:04 / 00:00:06 (71%) -[ 7.196559] statusline: A: 00:00:04 / 00:00:06 (72%) -[ 7.246878] statusline: A: 00:00:04 / 00:00:06 (73%) -[ 7.396458] statusline: A: 00:00:04 / 00:00:06 (75%) -[ 7.446670] statusline: A: 00:00:04 / 00:00:06 (76%) -[ 7.496756] statusline: A: 00:00:04 / 00:00:06 (77%) -[ 7.596958] statusline: A: 00:00:04 / 00:00:06 (78%) -[ 7.647000] statusline: A: 00:00:04 / 00:00:06 (79%) -[ 7.697314] statusline: A: 00:00:04 / 00:00:06 (80%) -[ 7.747343] statusline: A: 00:00:04 / 00:00:06 (81%) -[ 7.846469] statusline: A: 00:00:05 / 00:00:06 (83%) -[ 7.946975] statusline: A: 00:00:05 / 00:00:06 (84%) -[ 8.045892] statusline: A: 00:00:05 / 00:00:06 (86%) -[ 8.195448] statusline: A: 00:00:05 / 00:00:06 (88%) -[ 8.245884] statusline: A: 00:00:05 / 00:00:06 (89%) -[ 8.345091] statusline: A: 00:00:05 / 00:00:06 (91%) -[ 8.445541] statusline: A: 00:00:05 / 00:00:06 (92%) -[ 8.544822] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.545185] cplayer: audio filter EOF -[ 8.545190] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.545249] cplayer: audio draining -[ 8.545257] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.545273] cplayer: audio EOF reached -[ 8.545286] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.545300] cplayer: EOF code: 1 -[ 8.545306] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.545694] cplayer: Terminating demuxers... -[ 8.545712] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.546694] cplayer: Done terminating demuxers. -[ 8.546747] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.546830] cplayer: finished playback, success (reason 0) -[ 8.546839] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.546852] cplayer: Exiting... (End of file) -[ 8.546859] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.550652] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] -[ 8.550666] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.552456] mpris: Exiting... -[ 8.552468] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 8.555333] cplayer: draining left over audio -[ 8.555356] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 0.102722] mpris: Loading cplugin script ../mpris.so... +[ 0.103152] cplayer: Waiting for scripts... +[ 0.223326] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.223678] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.240163] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] +[ 0.240419] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.240594] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] +[ 0.240877] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.244888] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.257312] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.260648] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] +[ 0.260802] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.261011] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] +[ 0.261193] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.264629] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] +[ 0.266231] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] +[ 0.266543] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.266700] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.267123] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] +[ 0.267594] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] +[ 0.267797] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] +[ 0.268437] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] +[ 0.268824] cplayer: Set property: user-data/osc/visibility="auto" -> 1 +[ 0.268972] cplayer: Run command: disable-section, flags=64, args=[name="input"] +[ 0.269080] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] +[ 0.269415] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.271326] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.273343] cplayer: Done loading scripts. +[ 0.281531] cplayer: Running hook: ytdl_hook/on_load +[ 0.284292] cplayer: Running hook: ytdl_hook/on_load +[ 0.285016] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.286492] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.287336] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] +[ 0.371299] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.371933] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] +[ 0.372115] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.372280] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] +[ 0.372461] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.619306] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +[ 0.624167] cplayer: Running hook: ytdl_hook/on_preloaded +[ 0.629731] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) +[ 0.652972] cplayer: Starting playback... +[ 0.673578] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.675699] cplayer: AO: [null] 48000Hz stereo 2ch floatp +[ 0.675781] cplayer: AO: Description: Null audio output +[ 0.684938] cplayer: audio ready +[ 0.690243] cplayer: starting audio playback +[ 0.690403] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof (paused) +[ 0.709686] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.726645] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.335291] cplayer: Set property: pause=false -> 1 +[ 3.335340] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.336681] statusline: A: 00:00:00 / 00:00:06 (0%) +[ 3.389246] statusline: A: 00:00:00 / 00:00:06 (1%) +[ 3.441020] statusline: A: 00:00:00 / 00:00:06 (2%) +[ 3.492414] statusline: A: 00:00:00 / 00:00:06 (3%) +[ 3.596028] statusline: A: 00:00:00 / 00:00:06 (4%) +[ 3.649259] statusline: A: 00:00:00 / 00:00:06 (5%) +[ 3.700280] statusline: A: 00:00:00 / 00:00:06 (6%) +[ 3.753302] statusline: A: 00:00:00 / 00:00:06 (7%) +[ 3.806448] statusline: A: 00:00:00 / 00:00:06 (8%) +[ 3.859314] statusline: A: 00:00:00 / 00:00:06 (9%) +[ 3.963548] statusline: A: 00:00:00 / 00:00:06 (10%) +[ 4.016592] statusline: A: 00:00:00 / 00:00:06 (11%) +[ 4.067752] statusline: A: 00:00:00 / 00:00:06 (12%) +[ 4.123114] statusline: A: 00:00:00 / 00:00:06 (13%) +[ 4.173443] statusline: A: 00:00:00 / 00:00:06 (14%) +[ 4.227558] statusline: A: 00:00:00 / 00:00:06 (15%) +[ 4.279567] statusline: A: 00:00:00 / 00:00:06 (16%) +[ 4.337303] statusline: A: 00:00:01 / 00:00:06 (16%) +[ 4.389288] statusline: A: 00:00:01 / 00:00:06 (17%) +[ 4.443389] statusline: A: 00:00:01 / 00:00:06 (18%) +[ 4.500391] statusline: A: 00:00:01 / 00:00:06 (19%) +[ 4.557684] statusline: A: 00:00:01 / 00:00:06 (20%) +[ 4.613250] statusline: A: 00:00:01 / 00:00:06 (21%) +[ 4.664688] statusline: A: 00:00:01 / 00:00:06 (22%) +[ 4.715050] statusline: A: 00:00:01 / 00:00:06 (23%) +[ 4.781572] statusline: A: 00:00:01 / 00:00:06 (24%) +[ 4.890361] statusline: A: 00:00:01 / 00:00:06 (25%) +[ 4.941214] statusline: A: 00:00:01 / 00:00:06 (26%) +[ 4.994940] statusline: A: 00:00:01 / 00:00:06 (27%) +[ 5.046130] statusline: A: 00:00:01 / 00:00:06 (28%) +[ 5.097361] statusline: A: 00:00:01 / 00:00:06 (29%) +[ 5.149032] statusline: A: 00:00:01 / 00:00:06 (30%) +[ 5.205188] statusline: A: 00:00:01 / 00:00:06 (31%) +[ 5.323660] statusline: A: 00:00:01 / 00:00:06 (32%) +[ 5.376251] statusline: A: 00:00:02 / 00:00:06 (33%) +[ 5.426656] statusline: A: 00:00:02 / 00:00:06 (34%) +[ 5.476994] statusline: A: 00:00:02 / 00:00:06 (35%) +[ 5.539162] statusline: A: 00:00:02 / 00:00:06 (36%) +[ 5.592756] statusline: A: 00:00:02 / 00:00:06 (37%) +[ 5.645825] statusline: A: 00:00:02 / 00:00:06 (38%) +[ 5.696033] statusline: A: 00:00:02 / 00:00:06 (39%) +[ 5.805446] statusline: A: 00:00:02 / 00:00:06 (40%) +[ 5.858299] statusline: A: 00:00:02 / 00:00:06 (41%) +[ 5.911708] statusline: A: 00:00:02 / 00:00:06 (42%) +[ 5.962314] statusline: A: 00:00:02 / 00:00:06 (43%) +[ 6.017806] statusline: A: 00:00:02 / 00:00:06 (44%) +[ 6.069104] statusline: A: 00:00:02 / 00:00:06 (45%) +[ 6.125380] statusline: A: 00:00:02 / 00:00:06 (46%) +[ 6.227147] statusline: A: 00:00:02 / 00:00:06 (47%) +[ 6.277442] statusline: A: 00:00:02 / 00:00:06 (48%) +[ 6.328540] statusline: A: 00:00:03 / 00:00:06 (49%) +[ 6.380447] statusline: A: 00:00:03 / 00:00:06 (50%) +[ 6.431638] statusline: A: 00:00:03 / 00:00:06 (51%) +[ 6.539760] statusline: A: 00:00:03 / 00:00:06 (52%) +[ 6.591424] statusline: A: 00:00:03 / 00:00:06 (53%) +[ 6.645399] statusline: A: 00:00:03 / 00:00:06 (54%) +[ 6.695746] statusline: A: 00:00:03 / 00:00:06 (55%) +[ 6.753733] statusline: A: 00:00:03 / 00:00:06 (56%) +[ 6.820080] statusline: A: 00:00:03 / 00:00:06 (57%) +[ 6.875099] statusline: A: 00:00:03 / 00:00:06 (58%) +[ 6.925341] statusline: A: 00:00:03 / 00:00:06 (59%) +[ 7.037288] statusline: A: 00:00:03 / 00:00:06 (60%) +[ 7.090895] statusline: A: 00:00:03 / 00:00:06 (61%) +[ 7.141451] statusline: A: 00:00:03 / 00:00:06 (62%) +[ 7.195146] statusline: A: 00:00:03 / 00:00:06 (63%) +[ 7.249131] statusline: A: 00:00:03 / 00:00:06 (64%) +[ 7.306448] statusline: A: 00:00:03 / 00:00:06 (65%) +[ 7.363677] statusline: A: 00:00:04 / 00:00:06 (66%) +[ 7.414449] statusline: A: 00:00:04 / 00:00:06 (67%) +[ 7.515607] statusline: A: 00:00:04 / 00:00:06 (68%) +[ 7.566405] statusline: A: 00:00:04 / 00:00:06 (69%) +[ 7.617175] statusline: A: 00:00:04 / 00:00:06 (70%) +[ 7.668068] statusline: A: 00:00:04 / 00:00:06 (71%) +[ 7.719874] statusline: A: 00:00:04 / 00:00:06 (72%) +[ 7.821057] statusline: A: 00:00:04 / 00:00:06 (73%) +[ 7.879775] statusline: A: 00:00:04 / 00:00:06 (74%) +[ 7.932292] statusline: A: 00:00:04 / 00:00:06 (75%) +[ 7.985362] statusline: A: 00:00:04 / 00:00:06 (76%) +[ 8.037235] statusline: A: 00:00:04 / 00:00:06 (77%) +[ 8.088582] statusline: A: 00:00:04 / 00:00:06 (78%) +[ 8.146930] statusline: A: 00:00:04 / 00:00:06 (79%) +[ 8.255163] statusline: A: 00:00:04 / 00:00:06 (80%) +[ 8.306163] statusline: A: 00:00:04 / 00:00:06 (81%) +[ 8.361073] statusline: A: 00:00:05 / 00:00:06 (82%) +[ 8.411734] statusline: A: 00:00:05 / 00:00:06 (83%) +[ 8.471271] statusline: A: 00:00:05 / 00:00:06 (84%) +[ 8.528982] statusline: A: 00:00:05 / 00:00:06 (85%) +[ 8.583559] statusline: A: 00:00:05 / 00:00:06 (86%) +[ 8.637165] statusline: A: 00:00:05 / 00:00:06 (87%) +[ 8.751041] statusline: A: 00:00:05 / 00:00:06 (88%) +[ 8.805293] statusline: A: 00:00:05 / 00:00:06 (89%) +[ 8.856728] statusline: A: 00:00:05 / 00:00:06 (90%) +[ 8.910023] statusline: A: 00:00:05 / 00:00:06 (91%) +[ 8.962795] statusline: A: 00:00:05 / 00:00:06 (92%) +[ 9.015878] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 9.068345] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.075818] cplayer: audio filter EOF +[ 9.075832] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.076049] cplayer: audio draining +[ 9.076076] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.076105] cplayer: audio EOF reached +[ 9.076123] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.076168] cplayer: EOF code: 1 +[ 9.076187] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.081688] cplayer: Terminating demuxers... +[ 9.081754] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.085008] cplayer: Done terminating demuxers. +[ 9.085086] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.085269] cplayer: finished playback, success (reason 0) +[ 9.085293] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.085334] cplayer: Exiting... (End of file) +[ 9.085355] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.088217] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] +[ 9.088268] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.099677] mpris: Exiting... +[ 9.099744] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.120985] cplayer: draining left over audio +[ 9.121061] statusline: A: 00:00:05 / 00:00:06 (94%) make[2]: Leaving directory '/build/reproducible-path/mpv-mpris-0.7.1/test' make[2]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1/test' -./wrapper "stop" -./stop +./wrapper "play-pause" +./play-pause ++ test -n '' -++ exec ./env ./stop -/build/reproducible-path/mpv-mpris-0.7.1/test/env ./stop -++ basename ./stop -+ test=stop +++ exec ./env ./play-pause +/build/reproducible-path/mpv-mpris-0.7.1/test/env ./play-pause +++ basename ./play-pause ++ test=play-pause + '[' -z '' ']' + export MPV_MPRIS_TEST_PLUGIN=../mpris.so + MPV_MPRIS_TEST_PLUGIN=../mpris.so @@ -3444,19 +3547,19 @@ + xvfb-run --help + grep -- --auto-display + xvfb_auto=--auto-servernum -+ rm -f ./stop.xvfb.log ./stop.Xauthority -+ exec env MPV_MPRIS_TEST_NAME=stop XDG_RUNTIME_DIR=./dbus TEMPDIR=. TMPDIR=. TEMP=. TMP=. dbus-run-session -- xvfb-run --auto-servernum --error-file ./stop.xvfb.log -f ./stop.Xauthority ./stop -./stop -++ test -n stop -+++ basename ./stop -++ test=stop -++ '[' stop = setup ']' -++ input_json=./stop.mpv.ipc.input.json -++ output_json=./stop.mpv.ipc.output.json -++ ipc=./stop.mpv.ipc -++ log_prefix=./stop -++ mpv_log=./stop.mpv.log -++ socat_log=./stop.socat.log ++ rm -f ./play-pause.xvfb.log ./play-pause.Xauthority ++ exec env MPV_MPRIS_TEST_NAME=play-pause XDG_RUNTIME_DIR=./dbus TEMPDIR=. TMPDIR=. TEMP=. TMP=. dbus-run-session -- xvfb-run --auto-servernum --error-file ./play-pause.xvfb.log -f ./play-pause.Xauthority ./play-pause +./play-pause +++ test -n play-pause ++++ basename ./play-pause +++ test=play-pause +++ '[' play-pause = setup ']' +++ input_json=./play-pause.mpv.ipc.input.json +++ output_json=./play-pause.mpv.ipc.output.json +++ ipc=./play-pause.mpv.ipc +++ log_prefix=./play-pause +++ mpv_log=./play-pause.mpv.log +++ socat_log=./play-pause.socat.log ++ file=/usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga ++ params=() ++ '[' -n 1 ']' @@ -3465,20 +3568,28 @@ ++ '[' -n ../mpris.so ']' ++ params+=("--load-scripts=no" "--script=$MPV_MPRIS_TEST_PLUGIN") ++ unset MPV_MPRIS_TEST_PLUGIN MPV_MPRIS_TEST_PLAY MPV_MPRIS_TEST_MPV_IPC MPV_MPRIS_TEST_LOG -++ echo DISPLAY=:99 -DISPLAY=:99 -++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-Ri6EfpxpTF,guid=3340ec85a48806b1696d65e2662bff71 -DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-Ri6EfpxpTF,guid=3340ec85a48806b1696d65e2662bff71 -++ rm -f ./stop.mpv.ipc ./stop.mpv.log ./stop.socat.log -++ wait_for test -S ./stop.mpv.ipc +++ echo DISPLAY=:101 +DISPLAY=:101 +++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-uAh0gYirdV,guid=98cc03b8346f1a34ea2dcdba662c018a +DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-uAh0gYirdV,guid=98cc03b8346f1a34ea2dcdba662c018a +++ rm -f ./play-pause.mpv.ipc ./play-pause.mpv.log ./play-pause.socat.log +++ wait_for test -S ./play-pause.mpv.ipc ++ timeout=0 -++ test -S ./stop.mpv.ipc -++ mpv --pause --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./stop.mpv.log --input-ipc-server=./stop.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +++ test -S ./play-pause.mpv.ipc ++ '[' 0 -eq 60 ']' ++ sleep 1 +++ mpv --pause --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./play-pause.mpv.log --input-ipc-server=./play-pause.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +++ awk '/statusline.*Paused/{print>"/dev/stdout";next} 1' ++ timeout=1 -++ test -S ./stop.mpv.ipc +++ test -S ./play-pause.mpv.ipc +++ '[' 1 -eq 60 ']' +++ sleep 1 +++ timeout=2 +++ test -S ./play-pause.mpv.ipc +++ '[' 2 -eq 60 ']' +++ sleep 1 +++ timeout=3 +++ test -S ./play-pause.mpv.ipc ++ wait_for playerctl_list_all_is_mpv ++ timeout=0 ++ playerctl_list_all_is_mpv @@ -3493,21 +3604,82 @@ + check pause true + prop pause + jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' -+ cat ./stop.mpv.ipc.input.json ++ cat ./play-pause.mpv.ipc.input.json {"command":["get_property","pause"]} -+ rm -f ./stop.socat.log -+ socat -lf./stop.socat.log - UNIX-CONNECT:./stop.mpv.ipc -+ cat ./stop.socat.log -+ test '!' -s ./stop.socat.log -+ cat ./stop.mpv.ipc.output.json ++ rm -f ./play-pause.socat.log ++ socat -lf./play-pause.socat.log - UNIX-CONNECT:./play-pause.mpv.ipc ++ cat ./play-pause.socat.log ++ test '!' -s ./play-pause.socat.log ++ cat ./play-pause.mpv.ipc.output.json {"data":true,"request_id":0,"error":"success"} -+ test -s ./stop.mpv.ipc.output.json ++ test -s ./play-pause.mpv.ipc.output.json + val true + jq --exit-status --null-input 'inputs // {} | try (.data == true and .error == "success") catch false' true -+ '[' stop = stop ']' -+ playerctl stop -+ sleep 2 ++ playerctl play-pause ++ sleep 1 ++ status Playing ++ playerctl status ++ grep '^Playing$' +Playing ++ check pause false ++ prop pause ++ jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' ++ cat ./play-pause.mpv.ipc.input.json +{"command":["get_property","pause"]} ++ rm -f ./play-pause.socat.log ++ socat -lf./play-pause.socat.log - UNIX-CONNECT:./play-pause.mpv.ipc ++ cat ./play-pause.socat.log ++ test '!' -s ./play-pause.socat.log ++ cat ./play-pause.mpv.ipc.output.json +{"data":false,"request_id":0,"error":"success"} ++ test -s ./play-pause.mpv.ipc.output.json ++ val false ++ jq --exit-status --null-input 'inputs // {} | try (.data == false and .error == "success") catch false' +true ++ playerctl play-pause ++ sleep 1 ++ status Paused ++ playerctl status ++ grep '^Paused$' +Paused ++ check pause true ++ prop pause ++ jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' ++ cat ./play-pause.mpv.ipc.input.json +{"command":["get_property","pause"]} ++ rm -f ./play-pause.socat.log ++ socat -lf./play-pause.socat.log - UNIX-CONNECT:./play-pause.mpv.ipc ++ cat ./play-pause.socat.log ++ test '!' -s ./play-pause.socat.log ++ cat ./play-pause.mpv.ipc.output.json +{"data":true,"request_id":0,"error":"success"} ++ test -s ./play-pause.mpv.ipc.output.json ++ val true ++ jq --exit-status --null-input 'inputs // {} | try (.data == true and .error == "success") catch false' +true ++ playerctl play-pause ++ sleep 1 ++ status Playing ++ playerctl status ++ grep '^Playing$' +Playing ++ check pause false ++ prop pause ++ jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' ++ cat ./play-pause.mpv.ipc.input.json +{"command":["get_property","pause"]} ++ rm -f ./play-pause.socat.log ++ socat -lf./play-pause.socat.log - UNIX-CONNECT:./play-pause.mpv.ipc ++ cat ./play-pause.socat.log ++ test '!' -s ./play-pause.socat.log ++ cat ./play-pause.mpv.ipc.output.json +{"data":false,"request_id":0,"error":"success"} ++ test -s ./play-pause.mpv.ipc.output.json ++ val false ++ jq --exit-status --null-input 'inputs // {} | try (.data == false and .error == "success") catch false' +true ++ wait %1 [cplayer] mpv 0.37.0 Copyright © 2000-2023 mpv/MPlayer/mplayer2 projects [cplayer] libplacebo version: v6.338.2 [cplayer] FFmpeg version: 6.1.1-4 @@ -3521,7 +3693,7 @@ [cplayer] [cplayer] Configuration: -Dpython.bytecompile=-1 -Dlibmpv=true -Dbuild-date=false -Dcdda=enabled -Ddvdnav=enabled -Dsdl2=enabled -Dsndio=disabled -Ddvbin=enabled -Dprefix=/usr -Dlibdir=lib/arm-linux-gnueabihf -Dlocalstatedir=/var -Dsysconfdir=/etc -Dbuildtype=plain -Dwrap_mode=nodownload [cplayer] List of enabled features: alsa av-channel-layout avif-muxer caca cdda cplugins dmabuf-interop-gl dmabuf-wayland drm dvbin dvdnav egl egl-drm egl-helpers egl-wayland egl-x11 ffmpeg gbm gl glibc-thread-name glob glob-posix gpl iconv jack javascript jpeg jpegxl lavu-uuid lcms2 libarchive libass libavdevice libbluray libdl libm libplacebo librt linux-fstatfs lua52 manpage-build memfd-create noexecstack pipewire posix posix-shm ppoll pulse rubberband rubberband-3 sdl2 sdl2-audio sdl2-gamepad sdl2-video sixel spirv-cross threads uchardet vaapi vaapi-drm vaapi-wayland vaapi-x11 vdpau vector vk-khr-display vt.h vulkan vulkan-interop wayland wayland-protocols-1-27 wayland-protocols-1-31 wayland-protocols-1-32 x11 xv zimg zimg-st428 zlib -[cplayer] Command line options: '--pause' '--load-scripts=no' '--script=../mpris.so' '--vo=null' '--ao=null' '--msg-time' '--msg-module' '--msg-level=cplayer=trace,mpris=trace' '--log-file=./stop.mpv.log' '--input-ipc-server=./stop.mpv.ipc' '/usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga' +[cplayer] Command line options: '--pause' '--load-scripts=no' '--script=../mpris.so' '--vo=null' '--ao=null' '--msg-time' '--msg-module' '--msg-level=cplayer=trace,mpris=trace' '--log-file=./play-pause.mpv.log' '--input-ipc-server=./play-pause.mpv.ipc' '/usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga' [cplayer] mpv 0.37.0 Copyright © 2000-2023 mpv/MPlayer/mplayer2 projects [cplayer] libplacebo version: v6.338.2 [cplayer] FFmpeg version: 6.1.1-4 @@ -3545,103 +3717,188 @@ [cplayer] Setting option 'msg-time' = '' (flags = 8) [cplayer] Setting option 'msg-module' = '' (flags = 8) [cplayer] Setting option 'msg-level' = 'cplayer=trace,mpris=trace' (flags = 8) -[cplayer] Setting option 'log-file' = './stop.mpv.log' (flags = 8) -[cplayer] Setting option 'input-ipc-server' = './stop.mpv.ipc' (flags = 8) -[ 0.021149] mpris: Loading cplugin script ../mpris.so... -[ 0.021629] cplayer: Waiting for scripts... -[ 0.039383] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] -[ 0.039454] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.040187] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] -[ 0.040232] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.042612] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.042732] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.042834] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.042928] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.043110] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] -[ 0.043204] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.043265] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] -[ 0.043305] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.048770] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] -[ 0.048921] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] -[ 0.049011] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.049098] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.049223] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] -[ 0.049378] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] -[ 0.049434] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] -[ 0.049494] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] -[ 0.049610] cplayer: Set property: user-data/osc/visibility="auto" -> 1 -[ 0.049710] cplayer: Run command: disable-section, flags=64, args=[name="input"] -[ 0.049759] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] -[ 0.049854] cplayer: Set property: user-data/osc/margins={"r":0,"b":0,"l":0,"t":0} -> 1 -[ 0.050061] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.050133] cplayer: Done loading scripts. -[ 0.050404] cplayer: Running hook: ytdl_hook/on_load -[ 0.051423] cplayer: Running hook: ytdl_hook/on_load -[ 0.051686] cplayer: Set property: user-data/osc/margins={"r":0,"b":0,"l":0,"t":0} -> 1 -[ 0.053686] cplayer: Set property: user-data/osc/margins={"r":0,"b":0,"l":0,"t":0} -> 1 -[ 0.054004] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] -[ 0.061706] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga -[ 0.071777] cplayer: Running hook: ytdl_hook/on_preloaded -[ 0.072048] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) -[ 0.074938] cplayer: Starting playback... -[ 0.076439] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] -[ 0.076577] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.076751] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] -[ 0.077116] cplayer: AO: [null] 48000Hz stereo 2ch floatp -[ 0.077152] cplayer: AO: Description: Null audio output -[ 0.078791] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.078916] cplayer: audio ready -[ 0.078949] cplayer: starting audio playback -[ 0.079006] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof (paused) -[ 0.101810] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 0.107678] cplayer: Set property: user-data/osc/margins={"r":0,"b":0,"l":0,"t":0} -> 1 -[ 0.107697] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.810555] cplayer: Run command: stop, flags=64, args=[flags=""] -[ 2.810579] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.810627] cplayer: EOF code: 4 -[ 2.810633] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.811071] cplayer: Terminating demuxers... -[ 2.811084] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.811739] cplayer: Done terminating demuxers. -[ 2.811768] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.811837] cplayer: finished playback, success (reason 2) -[ 2.811863] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.811877] cplayer: Exiting... (End of file) -[ 2.811882] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.814950] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] -[ 2.814960] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.815824] mpris: Exiting... -[ 2.815851] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -+ ret_ipc=0 -+ prop pause -+ jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' -+ cat ./stop.mpv.ipc.input.json -{"command":["get_property","pause"]} -+ rm -f ./stop.socat.log -+ socat -lf./stop.socat.log - UNIX-CONNECT:./stop.mpv.ipc -+ ret_ipc=1 -+ cat ./stop.mpv.ipc.output.json -+ test '!' -s ./stop.mpv.ipc.output.json -+ '[' 1 -eq 0 ']' -+ '[' '!' -s ./stop.socat.log ']' -+ echo 'socat log contains these *expected* errors:' -socat log contains these *expected* errors: -+ cat ./stop.socat.log -2024/04/26 07:24:38 socat[7443] E connect(, AF=1 "./stop.mpv.ipc", 16): Connection refused -+ playerctl status -+ grep '^No players \(were \)\?found$' -No players found -+ '[' 1 -eq 0 ']' -+ '[' '!' -s ./stop.socat.log ']' +[cplayer] Setting option 'log-file' = './play-pause.mpv.log' (flags = 8) +[cplayer] Setting option 'input-ipc-server' = './play-pause.mpv.ipc' (flags = 8) +[ 0.254813] mpris: Loading cplugin script ../mpris.so... +[ 0.255427] cplayer: Waiting for scripts... +[ 0.302650] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] +[ 0.302938] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.303219] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] +[ 0.303331] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.332940] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.333202] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.333379] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.333545] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.334108] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] +[ 0.334243] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.334354] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] +[ 0.334505] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.354973] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] +[ 0.355473] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] +[ 0.355823] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.355980] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.356377] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] +[ 0.357375] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] +[ 0.357623] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] +[ 0.357824] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] +[ 0.358142] cplayer: Set property: user-data/osc/visibility="auto" -> 1 +[ 0.358261] cplayer: Run command: disable-section, flags=64, args=[name="input"] +[ 0.358341] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] +[ 0.358739] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.361143] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.362625] cplayer: Done loading scripts. +[ 0.366785] cplayer: Running hook: ytdl_hook/on_load +[ 0.370366] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.370827] cplayer: Running hook: ytdl_hook/on_load +[ 0.374424] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] +[ 0.455014] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.471615] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.472144] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] +[ 0.472300] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.472471] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] +[ 0.472586] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.520248] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +[ 0.523240] cplayer: Running hook: ytdl_hook/on_preloaded +[ 0.529087] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) +[ 0.542091] cplayer: Starting playback... +[ 0.554835] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.560259] cplayer: AO: [null] 48000Hz stereo 2ch floatp +[ 0.560349] cplayer: AO: Description: Null audio output +[ 0.575043] cplayer: audio ready +[ 0.575203] cplayer: starting audio playback +[ 0.575326] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof (paused) +[ 0.593735] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.612281] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 2.835166] cplayer: Set property: pause=false -> 1 +[ 2.835211] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 2.836835] statusline: A: 00:00:00 / 00:00:06 (0%) +[ 2.889546] statusline: A: 00:00:00 / 00:00:06 (1%) +[ 2.946170] statusline: A: 00:00:00 / 00:00:06 (2%) +[ 2.997333] statusline: A: 00:00:00 / 00:00:06 (3%) +[ 3.056230] statusline: A: 00:00:00 / 00:00:06 (4%) +[ 3.157381] statusline: A: 00:00:00 / 00:00:06 (5%) +[ 3.213050] statusline: A: 00:00:00 / 00:00:06 (6%) +[ 3.265167] statusline: A: 00:00:00 / 00:00:06 (7%) +[ 3.324400] statusline: A: 00:00:00 / 00:00:06 (8%) +[ 3.374803] statusline: A: 00:00:00 / 00:00:06 (9%) +[ 3.432832] statusline: A: 00:00:00 / 00:00:06 (10%) +[ 3.483249] statusline: A: 00:00:00 / 00:00:06 (11%) +[ 3.540214] statusline: A: 00:00:00 / 00:00:06 (12%) +[ 3.645713] statusline: A: 00:00:00 / 00:00:06 (13%) +[ 3.696116] statusline: A: 00:00:00 / 00:00:06 (14%) +[ 3.746889] statusline: A: 00:00:00 / 00:00:06 (15%) +[ 3.800939] statusline: A: 00:00:00 / 00:00:06 (16%) +[ 3.853369] statusline: A: 00:00:01 / 00:00:06 (17%) +[ 3.956546] statusline: A: 00:00:01 / 00:00:06 (18%) +[ 4.010428] statusline: A: 00:00:01 / 00:00:06 (19%) +[ 4.076047] statusline: A: 00:00:01 / 00:00:06 (20%) +[ 4.138342] statusline: A: 00:00:01 / 00:00:06 (21%) +[ 4.195535] statusline: A: 00:00:01 / 00:00:06 (22%) +[ 4.245879] statusline: A: 00:00:01 / 00:00:06 (23%) +[ 4.255205] cplayer: Set property: pause=true -> 1 +[ 4.255253] statusline: A: 00:00:01 / 00:00:06 (23%) +[ 4.255828] statusline: (Paused) A: 00:00:01 / 00:00:06 (23%) +[ 5.609871] cplayer: Set property: pause=false -> 1 +[ 5.609910] statusline: (Paused) A: 00:00:01 / 00:00:06 (23%) +[ 5.610573] statusline: A: 00:00:01 / 00:00:06 (23%) +[ 5.662989] statusline: A: 00:00:01 / 00:00:06 (24%) +[ 5.713134] statusline: A: 00:00:01 / 00:00:06 (25%) +[ 5.765710] statusline: A: 00:00:01 / 00:00:06 (26%) +[ 5.816723] statusline: A: 00:00:01 / 00:00:06 (27%) +[ 5.917720] statusline: A: 00:00:01 / 00:00:06 (28%) +[ 5.968186] statusline: A: 00:00:01 / 00:00:06 (29%) +[ 6.022799] statusline: A: 00:00:01 / 00:00:06 (30%) +[ 6.076810] statusline: A: 00:00:01 / 00:00:06 (31%) +[ 6.127827] statusline: A: 00:00:01 / 00:00:06 (32%) +[ 6.228436] statusline: A: 00:00:02 / 00:00:06 (33%) +[ 6.301469] statusline: A: 00:00:02 / 00:00:06 (34%) +[ 6.354253] statusline: A: 00:00:02 / 00:00:06 (35%) +[ 6.406352] statusline: A: 00:00:02 / 00:00:06 (36%) +[ 6.456755] statusline: A: 00:00:02 / 00:00:06 (37%) +[ 6.509045] statusline: A: 00:00:02 / 00:00:06 (38%) +[ 6.559505] statusline: A: 00:00:02 / 00:00:06 (39%) +[ 6.662496] statusline: A: 00:00:02 / 00:00:06 (40%) +[ 6.714525] statusline: A: 00:00:02 / 00:00:06 (41%) +[ 6.766529] statusline: A: 00:00:02 / 00:00:06 (42%) +[ 6.816992] statusline: A: 00:00:02 / 00:00:06 (43%) +[ 6.870255] statusline: A: 00:00:02 / 00:00:06 (44%) +[ 6.924234] statusline: A: 00:00:02 / 00:00:06 (45%) +[ 7.025916] statusline: A: 00:00:02 / 00:00:06 (46%) +[ 7.076286] statusline: A: 00:00:02 / 00:00:06 (47%) +[ 7.127779] statusline: A: 00:00:02 / 00:00:06 (48%) +[ 7.178608] statusline: A: 00:00:02 / 00:00:06 (49%) +[ 7.229001] statusline: A: 00:00:03 / 00:00:06 (50%) +[ 7.331010] statusline: A: 00:00:03 / 00:00:06 (51%) +[ 7.385752] statusline: A: 00:00:03 / 00:00:06 (52%) +[ 7.452621] statusline: A: 00:00:03 / 00:00:06 (53%) +[ 7.505894] statusline: A: 00:00:03 / 00:00:06 (54%) +[ 7.557838] statusline: A: 00:00:03 / 00:00:06 (55%) +[ 7.608238] statusline: A: 00:00:03 / 00:00:06 (56%) +[ 7.665942] statusline: A: 00:00:03 / 00:00:06 (57%) +[ 7.722974] statusline: A: 00:00:03 / 00:00:06 (58%) +[ 7.834128] statusline: A: 00:00:03 / 00:00:06 (59%) +[ 7.887545] statusline: A: 00:00:03 / 00:00:06 (60%) +[ 7.940985] statusline: A: 00:00:03 / 00:00:06 (61%) +[ 7.991341] statusline: A: 00:00:03 / 00:00:06 (62%) +[ 8.044863] statusline: A: 00:00:03 / 00:00:06 (63%) +[ 8.095150] statusline: A: 00:00:03 / 00:00:06 (64%) +[ 8.172898] statusline: A: 00:00:03 / 00:00:06 (65%) +[ 8.228145] statusline: A: 00:00:04 / 00:00:06 (66%) +[ 8.280769] statusline: A: 00:00:04 / 00:00:06 (67%) +[ 8.331242] statusline: A: 00:00:04 / 00:00:06 (68%) +[ 8.447541] statusline: A: 00:00:04 / 00:00:06 (70%) +[ 8.554415] statusline: A: 00:00:04 / 00:00:06 (71%) +[ 8.604793] statusline: A: 00:00:04 / 00:00:06 (72%) +[ 8.656012] statusline: A: 00:00:04 / 00:00:06 (73%) +[ 8.719485] statusline: A: 00:00:04 / 00:00:06 (74%) +[ 8.774781] statusline: A: 00:00:04 / 00:00:06 (75%) +[ 8.834862] statusline: A: 00:00:04 / 00:00:06 (76%) +[ 8.885263] statusline: A: 00:00:04 / 00:00:06 (77%) +[ 8.942827] statusline: A: 00:00:04 / 00:00:06 (78%) +[ 9.010460] statusline: A: 00:00:04 / 00:00:06 (79%) +[ 9.068961] statusline: A: 00:00:04 / 00:00:06 (80%) +[ 9.194904] statusline: A: 00:00:05 / 00:00:06 (82%) +[ 9.263284] statusline: A: 00:00:05 / 00:00:06 (83%) +[ 9.319357] statusline: A: 00:00:05 / 00:00:06 (84%) +[ 9.374346] statusline: A: 00:00:05 / 00:00:06 (85%) +[ 9.432518] statusline: A: 00:00:05 / 00:00:06 (86%) +[ 9.490288] statusline: A: 00:00:05 / 00:00:06 (87%) +[ 9.616630] statusline: A: 00:00:05 / 00:00:06 (89%) +[ 9.738257] statusline: A: 00:00:05 / 00:00:06 (91%) +[ 9.806342] statusline: A: 00:00:05 / 00:00:06 (92%) +[ 9.866238] statusline: A: 00:00:05 / 00:00:06 (93%) +[ 9.926284] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.927989] cplayer: audio filter EOF +[ 9.928003] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.928176] cplayer: audio draining +[ 9.928215] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.928255] cplayer: audio EOF reached +[ 9.928273] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.928318] cplayer: EOF code: 1 +[ 9.928335] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.929334] cplayer: Terminating demuxers... +[ 9.929373] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.930510] cplayer: Done terminating demuxers. +[ 9.934012] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.934259] cplayer: finished playback, success (reason 0) +[ 9.934283] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.934331] cplayer: Exiting... (End of file) +[ 9.934353] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.944974] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] +[ 9.944985] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.957142] mpris: Exiting... +[ 9.957178] statusline: A: 00:00:05 / 00:00:06 (94%) +[ 9.978268] cplayer: draining left over audio +[ 9.978326] statusline: A: 00:00:05 / 00:00:06 (94%) make[2]: Leaving directory '/build/reproducible-path/mpv-mpris-0.7.1/test' make[2]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1/test' -./wrapper "quit" -./quit +./wrapper "stop" +./stop ++ test -n '' -++ exec ./env ./quit -/build/reproducible-path/mpv-mpris-0.7.1/test/env ./quit -++ basename ./quit -+ test=quit +++ exec ./env ./stop +/build/reproducible-path/mpv-mpris-0.7.1/test/env ./stop +++ basename ./stop ++ test=stop + '[' -z '' ']' + export MPV_MPRIS_TEST_PLUGIN=../mpris.so + MPV_MPRIS_TEST_PLUGIN=../mpris.so @@ -3694,22 +3951,22 @@ + MPV_MPRIS_TEST_DBUS=./dbus + mkdir -p -m 0700 ./dbus + export -n MPV_MPRIS_TEST_DBUS MPV_MPRIS_TEST_XAUTH MPV_MPRIS_TEST_TMP -+ xvfb-run --help + grep -- --auto-display ++ xvfb-run --help + xvfb_auto=--auto-servernum -+ rm -f ./quit.xvfb.log ./quit.Xauthority -+ exec env MPV_MPRIS_TEST_NAME=quit XDG_RUNTIME_DIR=./dbus TEMPDIR=. TMPDIR=. TEMP=. TMP=. dbus-run-session -- xvfb-run --auto-servernum --error-file ./quit.xvfb.log -f ./quit.Xauthority ./quit -./quit -++ test -n quit -+++ basename ./quit -++ test=quit -++ '[' quit = setup ']' -++ input_json=./quit.mpv.ipc.input.json -++ output_json=./quit.mpv.ipc.output.json -++ ipc=./quit.mpv.ipc -++ log_prefix=./quit -++ mpv_log=./quit.mpv.log -++ socat_log=./quit.socat.log ++ rm -f ./stop.xvfb.log ./stop.Xauthority ++ exec env MPV_MPRIS_TEST_NAME=stop XDG_RUNTIME_DIR=./dbus TEMPDIR=. TMPDIR=. TEMP=. TMP=. dbus-run-session -- xvfb-run --auto-servernum --error-file ./stop.xvfb.log -f ./stop.Xauthority ./stop +./stop +++ test -n stop ++++ basename ./stop +++ test=stop +++ '[' stop = setup ']' +++ input_json=./stop.mpv.ipc.input.json +++ output_json=./stop.mpv.ipc.output.json +++ ipc=./stop.mpv.ipc +++ log_prefix=./stop +++ mpv_log=./stop.mpv.log +++ socat_log=./stop.socat.log ++ file=/usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga ++ params=() ++ '[' -n 1 ']' @@ -3718,20 +3975,28 @@ ++ '[' -n ../mpris.so ']' ++ params+=("--load-scripts=no" "--script=$MPV_MPRIS_TEST_PLUGIN") ++ unset MPV_MPRIS_TEST_PLUGIN MPV_MPRIS_TEST_PLAY MPV_MPRIS_TEST_MPV_IPC MPV_MPRIS_TEST_LOG -++ echo DISPLAY=:101 -DISPLAY=:101 -++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-2M5Jni7aQx,guid=384d182ebc5356d1f9a6d397662bff74 -DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-2M5Jni7aQx,guid=384d182ebc5356d1f9a6d397662bff74 -++ rm -f ./quit.mpv.ipc ./quit.mpv.log ./quit.socat.log -++ wait_for test -S ./quit.mpv.ipc +++ echo DISPLAY=:99 +DISPLAY=:99 +++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-2Vv9YIF8MI,guid=52b22998ebc5ba65789043bf662c0196 +DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-2Vv9YIF8MI,guid=52b22998ebc5ba65789043bf662c0196 +++ rm -f ./stop.mpv.ipc ./stop.mpv.log ./stop.socat.log +++ wait_for test -S ./stop.mpv.ipc ++ timeout=0 -++ test -S ./quit.mpv.ipc -++ mpv --pause --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./quit.mpv.log --input-ipc-server=./quit.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +++ test -S ./stop.mpv.ipc +++ mpv --pause --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./stop.mpv.log --input-ipc-server=./stop.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga ++ '[' 0 -eq 60 ']' ++ sleep 1 +++ awk '/statusline.*Paused/{print>"/dev/stdout";next} 1' ++ timeout=1 -++ test -S ./quit.mpv.ipc +++ test -S ./stop.mpv.ipc +++ '[' 1 -eq 60 ']' +++ sleep 1 +++ timeout=2 +++ test -S ./stop.mpv.ipc +++ '[' 2 -eq 60 ']' +++ sleep 1 +++ timeout=3 +++ test -S ./stop.mpv.ipc ++ wait_for playerctl_list_all_is_mpv ++ timeout=0 ++ playerctl_list_all_is_mpv @@ -3740,29 +4005,26 @@ ++ test mpv = mpv ++ sleep 2 + status Paused -+ playerctl status + grep '^Paused$' ++ playerctl status Paused + check pause true + prop pause + jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' -+ cat ./quit.mpv.ipc.input.json ++ cat ./stop.mpv.ipc.input.json {"command":["get_property","pause"]} -+ rm -f ./quit.socat.log -+ socat -lf./quit.socat.log - UNIX-CONNECT:./quit.mpv.ipc -+ cat ./quit.socat.log -+ test '!' -s ./quit.socat.log -+ cat ./quit.mpv.ipc.output.json ++ rm -f ./stop.socat.log ++ socat -lf./stop.socat.log - UNIX-CONNECT:./stop.mpv.ipc ++ cat ./stop.socat.log ++ test '!' -s ./stop.socat.log ++ cat ./stop.mpv.ipc.output.json {"data":true,"request_id":0,"error":"success"} -+ test -s ./quit.mpv.ipc.output.json ++ test -s ./stop.mpv.ipc.output.json + val true + jq --exit-status --null-input 'inputs // {} | try (.data == true and .error == "success") catch false' true -+ '[' quit = stop ']' -+ '[' quit = quit ']' -+ mpris_quit -+ dbus-send --print-reply --dest=org.mpris.MediaPlayer2.mpv /org/mpris/MediaPlayer2 org.mpris.MediaPlayer2.Quit -method return time=1714159480.063565 sender=:1.0 -> destination=:1.3 serial=5 reply_serial=2 ++ '[' stop = stop ']' ++ playerctl stop + sleep 2 [cplayer] mpv 0.37.0 Copyright © 2000-2023 mpv/MPlayer/mplayer2 projects [cplayer] libplacebo version: v6.338.2 @@ -3777,7 +4039,7 @@ [cplayer] [cplayer] Configuration: -Dpython.bytecompile=-1 -Dlibmpv=true -Dbuild-date=false -Dcdda=enabled -Ddvdnav=enabled -Dsdl2=enabled -Dsndio=disabled -Ddvbin=enabled -Dprefix=/usr -Dlibdir=lib/arm-linux-gnueabihf -Dlocalstatedir=/var -Dsysconfdir=/etc -Dbuildtype=plain -Dwrap_mode=nodownload [cplayer] List of enabled features: alsa av-channel-layout avif-muxer caca cdda cplugins dmabuf-interop-gl dmabuf-wayland drm dvbin dvdnav egl egl-drm egl-helpers egl-wayland egl-x11 ffmpeg gbm gl glibc-thread-name glob glob-posix gpl iconv jack javascript jpeg jpegxl lavu-uuid lcms2 libarchive libass libavdevice libbluray libdl libm libplacebo librt linux-fstatfs lua52 manpage-build memfd-create noexecstack pipewire posix posix-shm ppoll pulse rubberband rubberband-3 sdl2 sdl2-audio sdl2-gamepad sdl2-video sixel spirv-cross threads uchardet vaapi vaapi-drm vaapi-wayland vaapi-x11 vdpau vector vk-khr-display vt.h vulkan vulkan-interop wayland wayland-protocols-1-27 wayland-protocols-1-31 wayland-protocols-1-32 x11 xv zimg zimg-st428 zlib -[cplayer] Command line options: '--pause' '--load-scripts=no' '--script=../mpris.so' '--vo=null' '--ao=null' '--msg-time' '--msg-module' '--msg-level=cplayer=trace,mpris=trace' '--log-file=./quit.mpv.log' '--input-ipc-server=./quit.mpv.ipc' '/usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga' +[cplayer] Command line options: '--pause' '--load-scripts=no' '--script=../mpris.so' '--vo=null' '--ao=null' '--msg-time' '--msg-module' '--msg-level=cplayer=trace,mpris=trace' '--log-file=./stop.mpv.log' '--input-ipc-server=./stop.mpv.ipc' '/usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga' [cplayer] mpv 0.37.0 Copyright © 2000-2023 mpv/MPlayer/mplayer2 projects [cplayer] libplacebo version: v6.338.2 [cplayer] FFmpeg version: 6.1.1-4 @@ -3801,103 +4063,105 @@ [cplayer] Setting option 'msg-time' = '' (flags = 8) [cplayer] Setting option 'msg-module' = '' (flags = 8) [cplayer] Setting option 'msg-level' = 'cplayer=trace,mpris=trace' (flags = 8) -[cplayer] Setting option 'log-file' = './quit.mpv.log' (flags = 8) -[cplayer] Setting option 'input-ipc-server' = './quit.mpv.ipc' (flags = 8) -[ 0.026767] mpris: Loading cplugin script ../mpris.so... -[ 0.026971] cplayer: Waiting for scripts... -[ 0.049703] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] -[ 0.049811] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.049908] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] -[ 0.049968] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.052185] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.052281] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] -[ 0.052426] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.052500] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] -[ 0.052698] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.052768] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.052871] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.052990] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.053065] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] -[ 0.053305] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] -[ 0.053384] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] -[ 0.053484] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] -[ 0.053570] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.053771] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] -[ 0.053850] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] -[ 0.053950] cplayer: Set property: user-data/osc/visibility="auto" -> 1 -[ 0.054015] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.054067] cplayer: Run command: disable-section, flags=64, args=[name="input"] -[ 0.054116] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] -[ 0.054230] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 -[ 0.054469] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.054535] cplayer: Done loading scripts. -[ 0.054804] cplayer: Running hook: ytdl_hook/on_load -[ 0.055620] cplayer: Running hook: ytdl_hook/on_load -[ 0.056205] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 -[ 0.058386] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 -[ 0.058634] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] -[ 0.066859] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga -[ 0.077189] cplayer: Running hook: ytdl_hook/on_preloaded -[ 0.077462] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) -[ 0.080566] cplayer: Starting playback... -[ 0.081998] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] -[ 0.082104] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.082384] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] -[ 0.082507] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.082892] cplayer: AO: [null] 48000Hz stereo 2ch floatp -[ 0.082925] cplayer: AO: Description: Null audio output -[ 0.084558] cplayer: audio ready -[ 0.084597] cplayer: starting audio playback -[ 0.084677] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof (paused) -[ 0.106116] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 0.113448] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 -[ 0.113469] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.776215] cplayer: Run command: quit, flags=64, args=[code="0"] -[ 2.776240] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.776280] cplayer: EOF code: 5 -[ 2.776288] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.776755] cplayer: Terminating demuxers... -[ 2.776771] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.777422] cplayer: Done terminating demuxers. -[ 2.777452] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.777535] cplayer: finished playback, success (reason 3) -[ 2.777547] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.777559] cplayer: Exiting... (Quit) -[ 2.777564] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.778765] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] -[ 2.778780] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.780288] mpris: Exiting... -[ 2.780320] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[cplayer] Setting option 'log-file' = './stop.mpv.log' (flags = 8) +[cplayer] Setting option 'input-ipc-server' = './stop.mpv.ipc' (flags = 8) +[ 0.066858] mpris: Loading cplugin script ../mpris.so... +[ 0.067338] cplayer: Waiting for scripts... +[ 0.239935] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] +[ 0.240084] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.240199] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] +[ 0.240285] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.243079] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.243349] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.243534] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.243710] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.245344] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] +[ 0.245485] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.245601] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] +[ 0.245696] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.269776] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] +[ 0.272305] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] +[ 0.272553] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.272658] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.273120] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] +[ 0.273587] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] +[ 0.273746] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] +[ 0.274093] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] +[ 0.274365] cplayer: Set property: user-data/osc/visibility="auto" -> 1 +[ 0.274468] cplayer: Run command: disable-section, flags=64, args=[name="input"] +[ 0.274546] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] +[ 0.274748] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.275397] cplayer: Done loading scripts. +[ 0.275884] cplayer: Running hook: ytdl_hook/on_load +[ 0.282807] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.286736] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.290137] cplayer: Running hook: ytdl_hook/on_load +[ 0.294458] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.295133] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] +[ 0.434700] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] +[ 0.434928] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.435080] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] +[ 0.435194] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.446236] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +[ 0.451854] cplayer: Running hook: ytdl_hook/on_preloaded +[ 0.452953] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) +[ 0.467941] cplayer: Starting playback... +[ 0.478555] cplayer: AO: [null] 48000Hz stereo 2ch floatp +[ 0.478665] cplayer: AO: Description: Null audio output +[ 0.494083] cplayer: audio ready +[ 0.494239] cplayer: starting audio playback +[ 0.494407] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof (paused) +[ 0.494615] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 0.498777] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.498816] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 0.538031] cplayer: Set property: user-data/osc/margins={"b":0,"r":0,"t":0,"l":0} -> 1 +[ 0.538092] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.111332] cplayer: Run command: stop, flags=64, args=[flags=""] +[ 3.111371] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.111500] cplayer: EOF code: 4 +[ 3.111522] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.112607] cplayer: Terminating demuxers... +[ 3.112637] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.115222] cplayer: Done terminating demuxers. +[ 3.115282] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.115484] cplayer: finished playback, success (reason 2) +[ 3.115509] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.115556] cplayer: Exiting... (End of file) +[ 3.115575] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.133463] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] +[ 3.133518] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.134140] mpris: Exiting... +[ 3.134182] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) + ret_ipc=0 + prop pause + jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' -+ cat ./quit.mpv.ipc.input.json ++ cat ./stop.mpv.ipc.input.json {"command":["get_property","pause"]} -+ rm -f ./quit.socat.log -+ socat -lf./quit.socat.log - UNIX-CONNECT:./quit.mpv.ipc ++ rm -f ./stop.socat.log ++ socat -lf./stop.socat.log - UNIX-CONNECT:./stop.mpv.ipc + ret_ipc=1 -+ cat ./quit.mpv.ipc.output.json -+ test '!' -s ./quit.mpv.ipc.output.json ++ cat ./stop.mpv.ipc.output.json ++ test '!' -s ./stop.mpv.ipc.output.json + '[' 1 -eq 0 ']' -+ '[' '!' -s ./quit.socat.log ']' ++ '[' '!' -s ./stop.socat.log ']' + echo 'socat log contains these *expected* errors:' socat log contains these *expected* errors: -+ cat ./quit.socat.log -2024/04/26 07:24:42 socat[7485] E connect(, AF=1 "./quit.mpv.ipc", 16): Connection refused ++ cat ./stop.socat.log +2024/04/27 09:33:50 socat[24185] E connect(, AF=1 "./stop.mpv.ipc", 16): Connection refused + playerctl status + grep '^No players \(were \)\?found$' No players found + '[' 1 -eq 0 ']' -+ '[' '!' -s ./quit.socat.log ']' ++ '[' '!' -s ./stop.socat.log ']' make[2]: Leaving directory '/build/reproducible-path/mpv-mpris-0.7.1/test' make[2]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1/test' -./wrapper "play-pause" -./play-pause +./wrapper "quit" +./quit ++ test -n '' -++ exec ./env ./play-pause -/build/reproducible-path/mpv-mpris-0.7.1/test/env ./play-pause -++ basename ./play-pause -+ test=play-pause +++ exec ./env ./quit +/build/reproducible-path/mpv-mpris-0.7.1/test/env ./quit +++ basename ./quit ++ test=quit + '[' -z '' ']' + export MPV_MPRIS_TEST_PLUGIN=../mpris.so + MPV_MPRIS_TEST_PLUGIN=../mpris.so @@ -3953,19 +4217,19 @@ + xvfb-run --help + grep -- --auto-display + xvfb_auto=--auto-servernum -+ rm -f ./play-pause.xvfb.log ./play-pause.Xauthority -+ exec env MPV_MPRIS_TEST_NAME=play-pause XDG_RUNTIME_DIR=./dbus TEMPDIR=. TMPDIR=. TEMP=. TMP=. dbus-run-session -- xvfb-run --auto-servernum --error-file ./play-pause.xvfb.log -f ./play-pause.Xauthority ./play-pause -./play-pause -++ test -n play-pause -+++ basename ./play-pause -++ test=play-pause -++ '[' play-pause = setup ']' -++ input_json=./play-pause.mpv.ipc.input.json -++ output_json=./play-pause.mpv.ipc.output.json -++ ipc=./play-pause.mpv.ipc -++ log_prefix=./play-pause -++ mpv_log=./play-pause.mpv.log -++ socat_log=./play-pause.socat.log ++ rm -f ./quit.xvfb.log ./quit.Xauthority ++ exec env MPV_MPRIS_TEST_NAME=quit XDG_RUNTIME_DIR=./dbus TEMPDIR=. TMPDIR=. TEMP=. TMP=. dbus-run-session -- xvfb-run --auto-servernum --error-file ./quit.xvfb.log -f ./quit.Xauthority ./quit +./quit +++ test -n quit ++++ basename ./quit +++ test=quit +++ '[' quit = setup ']' +++ input_json=./quit.mpv.ipc.input.json +++ output_json=./quit.mpv.ipc.output.json +++ ipc=./quit.mpv.ipc +++ log_prefix=./quit +++ mpv_log=./quit.mpv.log +++ socat_log=./quit.socat.log ++ file=/usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga ++ params=() ++ '[' -n 1 ']' @@ -3974,20 +4238,28 @@ ++ '[' -n ../mpris.so ']' ++ params+=("--load-scripts=no" "--script=$MPV_MPRIS_TEST_PLUGIN") ++ unset MPV_MPRIS_TEST_PLUGIN MPV_MPRIS_TEST_PLAY MPV_MPRIS_TEST_MPV_IPC MPV_MPRIS_TEST_LOG -++ echo DISPLAY=:100 -DISPLAY=:100 -++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-5bjYBXHfEw,guid=236163793102fd5f1900fbd4662bff70 -DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-5bjYBXHfEw,guid=236163793102fd5f1900fbd4662bff70 -++ rm -f ./play-pause.mpv.ipc ./play-pause.mpv.log ./play-pause.socat.log -++ wait_for test -S ./play-pause.mpv.ipc +++ echo DISPLAY=:102 +DISPLAY=:102 +++ echo DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-E9868pvQPC,guid=2b2c849fbf47b01423d4df7d662c0197 +DBUS_SESSION_BUS_ADDRESS=unix:path=/tmp/dbus-E9868pvQPC,guid=2b2c849fbf47b01423d4df7d662c0197 +++ rm -f ./quit.mpv.ipc ./quit.mpv.log ./quit.socat.log +++ wait_for test -S ./quit.mpv.ipc ++ timeout=0 -++ test -S ./play-pause.mpv.ipc +++ test -S ./quit.mpv.ipc ++ '[' 0 -eq 60 ']' ++ sleep 1 -++ mpv --pause --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./play-pause.mpv.log --input-ipc-server=./play-pause.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +++ mpv --pause --load-scripts=no --script=../mpris.so --vo=null --ao=null --msg-time --msg-module --msg-level=cplayer=trace,mpris=trace --log-file=./quit.mpv.log --input-ipc-server=./quit.mpv.ipc /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +++ awk '/statusline.*Paused/{print>"/dev/stdout";next} 1' ++ timeout=1 -++ test -S ./play-pause.mpv.ipc +++ test -S ./quit.mpv.ipc +++ '[' 1 -eq 60 ']' +++ sleep 1 +++ timeout=2 +++ test -S ./quit.mpv.ipc +++ '[' 2 -eq 60 ']' +++ sleep 1 +++ timeout=3 +++ test -S ./quit.mpv.ipc ++ wait_for playerctl_list_all_is_mpv ++ timeout=0 ++ playerctl_list_all_is_mpv @@ -4002,82 +4274,24 @@ + check pause true + prop pause + jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' -+ cat ./play-pause.mpv.ipc.input.json -{"command":["get_property","pause"]} -+ rm -f ./play-pause.socat.log -+ socat -lf./play-pause.socat.log - UNIX-CONNECT:./play-pause.mpv.ipc -+ cat ./play-pause.socat.log -+ test '!' -s ./play-pause.socat.log -+ cat ./play-pause.mpv.ipc.output.json -{"data":true,"request_id":0,"error":"success"} -+ test -s ./play-pause.mpv.ipc.output.json -+ val true -+ jq --exit-status --null-input 'inputs // {} | try (.data == true and .error == "success") catch false' -true -+ playerctl play-pause -+ sleep 1 -+ status Playing -+ playerctl status -+ grep '^Playing$' -Playing -+ check pause false -+ prop pause -+ jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' -+ cat ./play-pause.mpv.ipc.input.json -{"command":["get_property","pause"]} -+ rm -f ./play-pause.socat.log -+ socat -lf./play-pause.socat.log - UNIX-CONNECT:./play-pause.mpv.ipc -+ cat ./play-pause.socat.log -+ test '!' -s ./play-pause.socat.log -+ cat ./play-pause.mpv.ipc.output.json -{"data":false,"request_id":0,"error":"success"} -+ test -s ./play-pause.mpv.ipc.output.json -+ val false -+ jq --exit-status --null-input 'inputs // {} | try (.data == false and .error == "success") catch false' -true -+ playerctl play-pause -+ sleep 1 -+ status Paused -+ playerctl status -+ grep '^Paused$' -Paused -+ check pause true -+ prop pause -+ jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' -+ cat ./play-pause.mpv.ipc.input.json ++ cat ./quit.mpv.ipc.input.json {"command":["get_property","pause"]} -+ rm -f ./play-pause.socat.log -+ socat -lf./play-pause.socat.log - UNIX-CONNECT:./play-pause.mpv.ipc -+ cat ./play-pause.socat.log -+ test '!' -s ./play-pause.socat.log -+ cat ./play-pause.mpv.ipc.output.json ++ rm -f ./quit.socat.log ++ socat -lf./quit.socat.log - UNIX-CONNECT:./quit.mpv.ipc ++ cat ./quit.socat.log ++ test '!' -s ./quit.socat.log ++ cat ./quit.mpv.ipc.output.json {"data":true,"request_id":0,"error":"success"} -+ test -s ./play-pause.mpv.ipc.output.json ++ test -s ./quit.mpv.ipc.output.json + val true + jq --exit-status --null-input 'inputs // {} | try (.data == true and .error == "success") catch false' true -+ playerctl play-pause -+ sleep 1 -+ status Playing -+ playerctl status -+ grep '^Playing$' -Playing -+ check pause false -+ prop pause -+ jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' -+ cat ./play-pause.mpv.ipc.input.json -{"command":["get_property","pause"]} -+ rm -f ./play-pause.socat.log -+ socat -lf./play-pause.socat.log - UNIX-CONNECT:./play-pause.mpv.ipc -+ cat ./play-pause.socat.log -+ test '!' -s ./play-pause.socat.log -+ cat ./play-pause.mpv.ipc.output.json -{"data":false,"request_id":0,"error":"success"} -+ test -s ./play-pause.mpv.ipc.output.json -+ val false -+ jq --exit-status --null-input 'inputs // {} | try (.data == false and .error == "success") catch false' -true -+ wait %1 ++ '[' quit = stop ']' ++ '[' quit = quit ']' ++ mpris_quit ++ dbus-send --print-reply --dest=org.mpris.MediaPlayer2.mpv /org/mpris/MediaPlayer2 org.mpris.MediaPlayer2.Quit +method return time=1714160029.848682 sender=:1.0 -> destination=:1.3 serial=7 reply_serial=2 ++ sleep 2 [cplayer] mpv 0.37.0 Copyright © 2000-2023 mpv/MPlayer/mplayer2 projects [cplayer] libplacebo version: v6.338.2 [cplayer] FFmpeg version: 6.1.1-4 @@ -4091,7 +4305,7 @@ [cplayer] [cplayer] Configuration: -Dpython.bytecompile=-1 -Dlibmpv=true -Dbuild-date=false -Dcdda=enabled -Ddvdnav=enabled -Dsdl2=enabled -Dsndio=disabled -Ddvbin=enabled -Dprefix=/usr -Dlibdir=lib/arm-linux-gnueabihf -Dlocalstatedir=/var -Dsysconfdir=/etc -Dbuildtype=plain -Dwrap_mode=nodownload [cplayer] List of enabled features: alsa av-channel-layout avif-muxer caca cdda cplugins dmabuf-interop-gl dmabuf-wayland drm dvbin dvdnav egl egl-drm egl-helpers egl-wayland egl-x11 ffmpeg gbm gl glibc-thread-name glob glob-posix gpl iconv jack javascript jpeg jpegxl lavu-uuid lcms2 libarchive libass libavdevice libbluray libdl libm libplacebo librt linux-fstatfs lua52 manpage-build memfd-create noexecstack pipewire posix posix-shm ppoll pulse rubberband rubberband-3 sdl2 sdl2-audio sdl2-gamepad sdl2-video sixel spirv-cross threads uchardet vaapi vaapi-drm vaapi-wayland vaapi-x11 vdpau vector vk-khr-display vt.h vulkan vulkan-interop wayland wayland-protocols-1-27 wayland-protocols-1-31 wayland-protocols-1-32 x11 xv zimg zimg-st428 zlib -[cplayer] Command line options: '--pause' '--load-scripts=no' '--script=../mpris.so' '--vo=null' '--ao=null' '--msg-time' '--msg-module' '--msg-level=cplayer=trace,mpris=trace' '--log-file=./play-pause.mpv.log' '--input-ipc-server=./play-pause.mpv.ipc' '/usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga' +[cplayer] Command line options: '--pause' '--load-scripts=no' '--script=../mpris.so' '--vo=null' '--ao=null' '--msg-time' '--msg-module' '--msg-level=cplayer=trace,mpris=trace' '--log-file=./quit.mpv.log' '--input-ipc-server=./quit.mpv.ipc' '/usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga' [cplayer] mpv 0.37.0 Copyright © 2000-2023 mpv/MPlayer/mplayer2 projects [cplayer] libplacebo version: v6.338.2 [cplayer] FFmpeg version: 6.1.1-4 @@ -4115,168 +4329,95 @@ [cplayer] Setting option 'msg-time' = '' (flags = 8) [cplayer] Setting option 'msg-module' = '' (flags = 8) [cplayer] Setting option 'msg-level' = 'cplayer=trace,mpris=trace' (flags = 8) -[cplayer] Setting option 'log-file' = './play-pause.mpv.log' (flags = 8) -[cplayer] Setting option 'input-ipc-server' = './play-pause.mpv.ipc' (flags = 8) -[ 0.023969] mpris: Loading cplugin script ../mpris.so... -[ 0.026210] cplayer: Waiting for scripts... -[ 0.039646] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] -[ 0.039721] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.039762] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] -[ 0.039793] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.040177] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.040262] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.040533] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.040784] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.042047] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] -[ 0.042133] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.042198] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] -[ 0.042280] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.045509] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] -[ 0.045658] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] -[ 0.045755] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.045800] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.045961] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] -[ 0.046125] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] -[ 0.046212] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] -[ 0.046289] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] -[ 0.046404] cplayer: Set property: user-data/osc/visibility="auto" -> 1 -[ 0.046465] cplayer: Run command: disable-section, flags=64, args=[name="input"] -[ 0.046504] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] -[ 0.046592] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.046829] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] -[ 0.046900] cplayer: Done loading scripts. -[ 0.047150] cplayer: Running hook: ytdl_hook/on_load -[ 0.047883] cplayer: Running hook: ytdl_hook/on_load -[ 0.048357] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.050323] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.051556] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] -[ 0.060523] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga -[ 0.071123] cplayer: Running hook: ytdl_hook/on_preloaded -[ 0.071453] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) -[ 0.074422] cplayer: Starting playback... -[ 0.075223] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] -[ 0.075335] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.075442] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] -[ 0.075496] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] -[ 0.077547] cplayer: AO: [null] 48000Hz stereo 2ch floatp -[ 0.077586] cplayer: AO: Description: Null audio output -[ 0.080349] cplayer: audio ready -[ 0.080398] cplayer: starting audio playback -[ 0.080975] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof (paused) -[ 0.098161] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 0.103191] cplayer: Set property: user-data/osc/margins={"b":0,"l":0,"t":0,"r":0} -> 1 -[ 0.103208] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.808959] cplayer: Set property: pause=false -> 1 -[ 2.808988] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) -[ 2.809465] statusline: A: 00:00:00 / 00:00:06 (0%) -[ 2.859593] statusline: A: 00:00:00 / 00:00:06 (1%) -[ 2.910059] statusline: A: 00:00:00 / 00:00:06 (2%) -[ 3.059111] statusline: A: 00:00:00 / 00:00:06 (4%) -[ 3.109457] statusline: A: 00:00:00 / 00:00:06 (5%) -[ 3.208734] statusline: A: 00:00:00 / 00:00:06 (7%) -[ 3.309249] statusline: A: 00:00:00 / 00:00:06 (8%) -[ 3.408375] statusline: A: 00:00:00 / 00:00:06 (10%) -[ 3.458377] statusline: A: 00:00:00 / 00:00:06 (11%) -[ 3.558871] statusline: A: 00:00:00 / 00:00:06 (12%) -[ 3.609016] statusline: A: 00:00:00 / 00:00:06 (13%) -[ 3.708194] statusline: A: 00:00:00 / 00:00:06 (15%) -[ 3.758235] statusline: A: 00:00:00 / 00:00:06 (16%) -[ 3.808693] statusline: A: 00:00:01 / 00:00:06 (16%) -[ 3.859325] statusline: A: 00:00:01 / 00:00:06 (17%) -[ 3.895460] cplayer: Set property: pause=true -> 1 -[ 3.895481] statusline: A: 00:00:01 / 00:00:06 (17%) -[ 3.895770] statusline: (Paused) A: 00:00:01 / 00:00:06 (18%) -[ 4.979608] cplayer: Set property: pause=false -> 1 -[ 4.979636] statusline: (Paused) A: 00:00:01 / 00:00:06 (18%) -[ 4.979859] statusline: A: 00:00:01 / 00:00:06 (18%) -[ 5.029954] statusline: A: 00:00:01 / 00:00:06 (19%) -[ 5.079986] statusline: A: 00:00:01 / 00:00:06 (20%) -[ 5.180717] statusline: A: 00:00:01 / 00:00:06 (21%) -[ 5.279455] statusline: A: 00:00:01 / 00:00:06 (23%) -[ 5.379848] statusline: A: 00:00:01 / 00:00:06 (24%) -[ 5.429944] statusline: A: 00:00:01 / 00:00:06 (25%) -[ 5.480065] statusline: A: 00:00:01 / 00:00:06 (26%) -[ 5.578954] statusline: A: 00:00:01 / 00:00:06 (28%) -[ 5.728580] statusline: A: 00:00:01 / 00:00:06 (30%) -[ 5.778945] statusline: A: 00:00:01 / 00:00:06 (31%) -[ 5.829185] statusline: A: 00:00:01 / 00:00:06 (32%) -[ 5.928201] statusline: A: 00:00:02 / 00:00:06 (33%) -[ 5.978249] statusline: A: 00:00:02 / 00:00:06 (34%) -[ 6.028825] statusline: A: 00:00:02 / 00:00:06 (35%) -[ 6.079161] statusline: A: 00:00:02 / 00:00:06 (36%) -[ 6.177842] statusline: A: 00:00:02 / 00:00:06 (37%) -[ 6.228793] statusline: A: 00:00:02 / 00:00:06 (38%) -[ 6.327264] statusline: A: 00:00:02 / 00:00:06 (40%) -[ 6.377664] statusline: A: 00:00:02 / 00:00:06 (41%) -[ 6.477980] statusline: A: 00:00:02 / 00:00:06 (42%) -[ 6.576462] statusline: A: 00:00:02 / 00:00:06 (44%) -[ 6.626843] statusline: A: 00:00:02 / 00:00:06 (45%) -[ 6.726180] statusline: A: 00:00:02 / 00:00:06 (46%) -[ 6.776585] statusline: A: 00:00:02 / 00:00:06 (47%) -[ 6.875937] statusline: A: 00:00:02 / 00:00:06 (49%) -[ 6.926202] statusline: A: 00:00:03 / 00:00:06 (49%) -[ 6.976861] statusline: A: 00:00:03 / 00:00:06 (50%) -[ 7.075413] statusline: A: 00:00:03 / 00:00:06 (52%) -[ 7.126263] statusline: A: 00:00:03 / 00:00:06 (53%) -[ 7.225213] statusline: A: 00:00:03 / 00:00:06 (54%) -[ 7.275583] statusline: A: 00:00:03 / 00:00:06 (55%) -[ 7.325703] statusline: A: 00:00:03 / 00:00:06 (56%) -[ 7.424764] statusline: A: 00:00:03 / 00:00:06 (58%) -[ 7.525027] statusline: A: 00:00:03 / 00:00:06 (59%) -[ 7.575096] statusline: A: 00:00:03 / 00:00:06 (60%) -[ 7.625092] statusline: A: 00:00:03 / 00:00:06 (61%) -[ 7.675312] statusline: A: 00:00:03 / 00:00:06 (62%) -[ 7.725673] statusline: A: 00:00:03 / 00:00:06 (63%) -[ 7.874812] statusline: A: 00:00:03 / 00:00:06 (65%) -[ 7.925674] statusline: A: 00:00:04 / 00:00:06 (66%) -[ 8.024554] statusline: A: 00:00:04 / 00:00:06 (67%) -[ 8.075042] statusline: A: 00:00:04 / 00:00:06 (68%) -[ 8.125299] statusline: A: 00:00:04 / 00:00:06 (69%) -[ 8.224092] statusline: A: 00:00:04 / 00:00:06 (71%) -[ 8.324189] statusline: A: 00:00:04 / 00:00:06 (72%) -[ 8.374377] statusline: A: 00:00:04 / 00:00:06 (73%) -[ 8.424468] statusline: A: 00:00:04 / 00:00:06 (74%) -[ 8.474501] statusline: A: 00:00:04 / 00:00:06 (75%) -[ 8.524682] statusline: A: 00:00:04 / 00:00:06 (76%) -[ 8.624878] statusline: A: 00:00:04 / 00:00:06 (77%) -[ 8.674942] statusline: A: 00:00:04 / 00:00:06 (78%) -[ 8.725029] statusline: A: 00:00:04 / 00:00:06 (79%) -[ 8.775005] statusline: A: 00:00:04 / 00:00:06 (80%) -[ 8.875042] statusline: A: 00:00:04 / 00:00:06 (81%) -[ 8.925231] statusline: A: 00:00:05 / 00:00:06 (82%) -[ 8.975360] statusline: A: 00:00:05 / 00:00:06 (83%) -[ 9.025379] statusline: A: 00:00:05 / 00:00:06 (84%) -[ 9.075490] statusline: A: 00:00:05 / 00:00:06 (85%) -[ 9.175905] statusline: A: 00:00:05 / 00:00:06 (86%) -[ 9.226201] statusline: A: 00:00:05 / 00:00:06 (87%) -[ 9.276473] statusline: A: 00:00:05 / 00:00:06 (88%) -[ 9.326592] statusline: A: 00:00:05 / 00:00:06 (89%) -[ 9.476068] statusline: A: 00:00:05 / 00:00:06 (91%) -[ 9.526229] statusline: A: 00:00:05 / 00:00:06 (92%) -[ 9.576250] statusline: A: 00:00:05 / 00:00:06 (93%) -[ 9.626357] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.626886] cplayer: audio filter EOF -[ 9.626891] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.626959] cplayer: audio draining -[ 9.626966] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.626973] cplayer: audio EOF reached -[ 9.626978] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.626996] cplayer: EOF code: 1 -[ 9.627001] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.627353] cplayer: Terminating demuxers... -[ 9.627367] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.627844] cplayer: Done terminating demuxers. -[ 9.627870] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.627939] cplayer: finished playback, success (reason 0) -[ 9.627949] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.627969] cplayer: Exiting... (End of file) -[ 9.627974] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.628811] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] -[ 9.628865] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.630451] mpris: Exiting... -[ 9.630478] statusline: A: 00:00:05 / 00:00:06 (94%) -[ 9.632442] cplayer: draining left over audio -[ 9.632471] statusline: A: 00:00:05 / 00:00:06 (94%) +[cplayer] Setting option 'log-file' = './quit.mpv.log' (flags = 8) +[cplayer] Setting option 'input-ipc-server' = './quit.mpv.ipc' (flags = 8) +[ 0.063794] mpris: Loading cplugin script ../mpris.so... +[ 0.064356] cplayer: Waiting for scripts... +[ 0.207758] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.208146] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.208540] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.209082] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.209645] cplayer: Run command: define-section, flags=64, args=[name="input_console", contents="", flags="default"] +[ 0.209883] cplayer: Run command: enable-section, flags=64, args=[name="input_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.210108] cplayer: Run command: define-section, flags=64, args=[name="input_forced_console", contents="", flags="force"] +[ 0.210332] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_console", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.228722] cplayer: Run command: define-section, flags=64, args=[name="input_stats", contents="", flags="default"] +[ 0.229153] cplayer: Run command: enable-section, flags=64, args=[name="input_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.229342] cplayer: Run command: define-section, flags=64, args=[name="input_forced_stats", contents="", flags="force"] +[ 0.229521] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_stats", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.237670] cplayer: Run command: define-section, flags=64, args=[name="showhide", contents="mouse_move script-binding osc/__keybinding1\nmouse_leave script-binding osc/__keybinding2\n", flags="force"] +[ 0.238090] cplayer: Run command: define-section, flags=64, args=[name="showhide_wc", contents="mouse_move script-binding osc/__keybinding3\nmouse_leave script-binding osc/__keybinding4\n", flags="force"] +[ 0.238348] cplayer: Run command: enable-section, flags=64, args=[name="showhide", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.238476] cplayer: Run command: enable-section, flags=64, args=[name="showhide_wc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.238807] cplayer: Run command: define-section, flags=64, args=[name="input", contents="mbtn_left script-binding osc/__keybinding5\nshift+mbtn_left script-binding osc/__keybinding6\nmbtn_right script-binding osc/__keybinding7\nmbtn_mid script-binding osc/__keybinding8\nwheel_up script-binding osc/__keybinding9\nwheel_down script-binding osc/__keybinding10\nmbtn_left_dbl ignore\nshift+mbtn_left_dbl ignore\nmbtn_right_dbl ignore\n", flags="force"] +[ 0.239315] cplayer: Run command: enable-section, flags=64, args=[name="input", flags=""] +[ 0.239553] cplayer: Run command: define-section, flags=64, args=[name="window-controls", contents="mbtn_left script-binding osc/__keybinding11\n", flags="force"] +[ 0.239736] cplayer: Run command: enable-section, flags=64, args=[name="window-controls", flags=""] +[ 0.239996] cplayer: Set property: user-data/osc/visibility="auto" -> 1 +[ 0.240093] cplayer: Run command: disable-section, flags=64, args=[name="input"] +[ 0.240168] cplayer: Run command: disable-section, flags=64, args=[name="window-controls"] +[ 0.240375] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.241177] cplayer: Run command: show-text, flags=64, args=[text="", duration="-1", level="0"] +[ 0.241576] cplayer: Done loading scripts. +[ 0.242039] cplayer: Running hook: ytdl_hook/on_load +[ 0.246153] cplayer: Running hook: ytdl_hook/on_load +[ 0.252188] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.253396] cplayer: Run command: osd-overlay, flags=64, args=[id="1", format="ass-events", data="", res_x="854", res_y="854", z="1000", hidden="no", compute_bounds="no"] +[ 0.352558] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.358432] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.358982] cplayer: Run command: define-section, flags=64, args=[name="input_osc", contents="", flags="default"] +[ 0.359128] cplayer: Run command: enable-section, flags=64, args=[name="input_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.359264] cplayer: Run command: define-section, flags=64, args=[name="input_forced_osc", contents="", flags="force"] +[ 0.359375] cplayer: Run command: enable-section, flags=64, args=[name="input_forced_osc", flags="allow-hide-cursor+allow-vo-dragging"] +[ 0.364402] cplayer: Opening done: /usr/share/sounds/freedesktop/stereo/alarm-clock-elapsed.oga +[ 0.369365] cplayer: Running hook: ytdl_hook/on_preloaded +[ 0.372029] cplayer: (+) Audio --aid=1 (vorbis 2ch 48000Hz) +[ 0.381947] cplayer: Starting playback... +[ 0.389221] cplayer: AO: [null] 48000Hz stereo 2ch floatp +[ 0.389310] cplayer: AO: Description: Null audio output +[ 0.395793] cplayer: audio ready +[ 0.395899] cplayer: starting audio playback +[ 0.396011] cplayer: playback restart complete @ 0.000000, audio=playing, video=eof (paused) +[ 0.399758] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 0.402888] cplayer: Set property: user-data/osc/margins={"l":0,"b":0,"r":0,"t":0} -> 1 +[ 0.402938] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.413798] cplayer: Run command: quit, flags=64, args=[code="0"] +[ 3.413826] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.413950] cplayer: EOF code: 5 +[ 3.413974] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.415618] cplayer: Terminating demuxers... +[ 3.415656] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.417682] cplayer: Done terminating demuxers. +[ 3.417723] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.417967] cplayer: finished playback, success (reason 3) +[ 3.417989] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.418035] cplayer: Exiting... (Quit) +[ 3.418068] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.422507] cplayer: Run command: del, flags=64, args=[name="user-data/osc"] +[ 3.422564] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) +[ 3.433371] mpris: Exiting... +[ 3.433423] statusline: (Paused) A: 00:00:00 / 00:00:06 (0%) ++ ret_ipc=0 ++ prop pause ++ jq --null-input --compact-output --arg prop pause '{command: ["get_property", $prop]}' ++ cat ./quit.mpv.ipc.input.json +{"command":["get_property","pause"]} ++ rm -f ./quit.socat.log ++ socat -lf./quit.socat.log - UNIX-CONNECT:./quit.mpv.ipc ++ ret_ipc=1 ++ cat ./quit.mpv.ipc.output.json ++ test '!' -s ./quit.mpv.ipc.output.json ++ '[' 1 -eq 0 ']' ++ '[' '!' -s ./quit.socat.log ']' ++ echo 'socat log contains these *expected* errors:' +socat log contains these *expected* errors: ++ cat ./quit.socat.log +2024/04/27 09:33:51 socat[24263] E connect(, AF=1 "./quit.mpv.ipc", 16): Connection refused ++ playerctl status ++ grep '^No players \(were \)\?found$' +No players found ++ '[' 1 -eq 0 ']' ++ '[' '!' -s ./quit.socat.log ']' make[2]: Leaving directory '/build/reproducible-path/mpv-mpris-0.7.1/test' make[1]: Leaving directory '/build/reproducible-path/mpv-mpris-0.7.1' create-stamp debian/debhelper-build-stamp @@ -4287,7 +4428,7 @@ debian/rules override_dh_auto_install make[1]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1' dh_auto_install -- PREFIX=/usr - make -j3 install DESTDIR=/build/reproducible-path/mpv-mpris-0.7.1/debian/mpv-mpris AM_UPDATE_INFO_DIR=no "INSTALL=install --strip-program=true" PREFIX=/usr + make -j4 install DESTDIR=/build/reproducible-path/mpv-mpris-0.7.1/debian/mpv-mpris AM_UPDATE_INFO_DIR=no "INSTALL=install --strip-program=true" PREFIX=/usr make[2]: Entering directory '/build/reproducible-path/mpv-mpris-0.7.1' mkdir -p /build/reproducible-path/mpv-mpris-0.7.1/debian/mpv-mpris/usr/lib/mpv-mpris install --strip-program=true -t /build/reproducible-path/mpv-mpris-0.7.1/debian/mpv-mpris/usr/lib/mpv-mpris mpris.so @@ -4321,12 +4462,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/10102/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/10102/tmp/hooks/B01_cleanup finished I: unmounting dev/ptmx filesystem I: unmounting dev/pts filesystem I: unmounting dev/shm filesystem I: unmounting proc filesystem I: unmounting sys filesystem I: cleaning the build env -I: removing directory /srv/workspace/pbuilder/24439 and its subdirectories -I: Current time: Fri Apr 26 07:24:56 -12 2024 -I: pbuilder-time-stamp: 1714159496 +I: removing directory /srv/workspace/pbuilder/10102 and its subdirectories +I: Current time: Sat Apr 27 09:34:38 +14 2024 +I: pbuilder-time-stamp: 1714160078