�[2mserver # [  162.366160] systemd[1]: Reached target Timer Units.�[0m
�[2mserver # [  162.368444] systemd[1]: Starting nextcloud-update-db.service...�[0m
�[2mserver # [  162.371541] systemd[1]: Starting PHP FastCGI Process Manager service for pool nextcloud...�[0m
�[2mserver # [  162.601250] php-fpm[2421]: �[0;1;39m[NOTICE] fpm is running, pid 2421�[0m�[0m
�[2mserver # [  162.605113] php-fpm[2421]: �[0;1;39m[NOTICE] ready to handle connections�[0m�[0m
�[2mserver # [  162.607224] systemd[1]: Started PHP FastCGI Process Manager service for pool nextcloud.�[0m
�[2mserver # [  162.608057] php-fpm[2421]: �[0;1;39m[NOTICE] systemd monitor interval set to 10000ms�[0m�[0m
�[2mserver # [  162.608807] systemd[1]: Reached target PHP FastCGI Process manager pools target.�[0m
�[2mserver # [  162.610852] systemd[1]: Reached target Multi-User System.�[0m
(finished: waiting for unit phpfpm-nextcloud.service, in 163.70 seconds)
server: �[1m�[32mwaiting for unit nginx.service�[0m
(finished: waiting for unit nginx.service, in 0.06 seconds)
server: �[1m�[32mwaiting for UNIX-domain stream on '/run/phpfpm/nextcloud.sock'�[0m
(finished: waiting for UNIX-domain stream on '/run/phpfpm/nextcloud.sock', in 0.05 seconds)
server: �[1m�[32mmust succeed: mkdir -p /tmp/shared/tmpjg72ar7f�[0m
(finished: must succeed: mkdir -p /tmp/shared/tmpjg72ar7f, in 0.03 seconds)
server: �[1m�[32mmust succeed: cp -r /etc/ssl/certs/ca-certificates.crt /tmp/shared/tmpjg72ar7f/ca-certificates.crt�[0m
(finished: must succeed: cp -r /etc/ssl/certs/ca-certificates.crt /tmp/shared/tmpjg72ar7f/ca-certificates.crt, in 0.03 seconds)
client: �[1m�[32mmust succeed: rm -r /etc/ssl/certs�[0m
client: �[1m�[32mwaiting for the VM to finish booting�[0m
client: Guest shell says: b'Spawning backdoor root shell...\n'
client: connected to guest root shell
client: (connecting took 0.00 seconds)
(finished: waiting for the VM to finish booting, in 0.00 seconds)
(finished: must succeed: rm -r /etc/ssl/certs, in 0.02 seconds)
client: �[1m�[32mmust succeed: mkdir -p /tmp/shared/tmpgdguy8im�[0m
(finished: must succeed: mkdir -p /tmp/shared/tmpgdguy8im, in 0.02 seconds)
client: �[1m�[32mmust succeed: mkdir -p /etc/ssl/certs�[0m
(finished: must succeed: mkdir -p /etc/ssl/certs, in 0.02 seconds)
client: �[1m�[32mmust succeed: cp -r /tmp/shared/tmpgdguy8im/ca-certificates.crt /etc/ssl/certs/ca-certificates.crt�[0m
(finished: must succeed: cp -r /tmp/shared/tmpgdguy8im/ca-certificates.crt /etc/ssl/certs/ca-certificates.crt, in 0.02 seconds)
�[1m�[32msubtest: access�[0m
curl --show-error --location --cookie-jar cookie.txt --cookie cookie.txt --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 --connect-to auth.example.com:443:server:443 --silent --output /dev/null --write-out '{"code":%{response_code}}' https://n.example.com
�[2mserver # [  168.969464] systemd[1]: Started nextcloud-update-db.service.�[0m
�[2mserver # [  168.970847] systemd[1]: Startup finished in 1.979s (kernel) + 2min 46.990s (userspace) = 2min 48.970s.�[0m
(finished: subtest: access, in 13.05 seconds)
�[1m�[32msubtest: extraScript�[0m
�[1m�[32msubtest: cron job succeeds�[0m
�[2mserver # [  176.610779] systemd[1]: Starting nextcloud-cron.service...�[0m
�[2mserver # [  179.268224] nextcloud-update-db-start[2454]: Done.�[0m
�[2mserver # [  183.592072] systemd[1]: Started nextcloud-cron.service.�[0m
�[2mserver # [  188.686736] nextcloud-update-db-start[2470]: Adding additional fs_storage_path_prefix index to the oc_filecache table, this can take some time...�[0m
�[2mserver # [  190.156227] Nextcloud[2479]: �[0;1;31m{"reqId":"Evl982FOpd0eudwaJCIE","level":3,"time":"2024-09-16T01:32:26+00:00","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"dns_get_record(): A temporary server error occurred. at /nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/lib/private/Http/Client/DnsPinMiddleware.php#111","userAgent":"--","version":"28.0.10.1","data":{"app":"PHP"}}�[0m�[0m
�[2mserver # [  190.164468] Nextcloud[2479]: �[0;1;31m{"reqId":"Evl982FOpd0eudwaJCIE","level":3,"time":"2024-09-16T01:32:26+00:00","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"dns_get_record(): A temporary server error occurred. at /nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/lib/private/Http/Client/DnsPinMiddleware.php#111","userAgent":"--","version":"28.0.10.1","data":{"app":"PHP"}}�[0m�[0m
�[2mserver # [  190.174580] Nextcloud[2479]: �[0;1;31m{"reqId":"Evl982FOpd0eudwaJCIE","level":3,"time":"2024-09-16T01:32:26+00:00","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"dns_get_record(): A temporary server error occurred. at /nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/lib/private/Http/Client/DnsPinMiddleware.php#111","userAgent":"--","version":"28.0.10.1","data":{"app":"PHP"}}�[0m�[0m
�[2mserver # [  190.184072] Nextcloud[2479]: �[0;1;31m{"reqId":"Evl982FOpd0eudwaJCIE","level":3,"time":"2024-09-16T01:32:26+00:00","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"dns_get_record(): A temporary server error occurred. at /nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/lib/private/Http/Client/DnsPinMiddleware.php#111","userAgent":"--","version":"28.0.10.1","data":{"app":"PHP"}}�[0m�[0m
�[2mserver # [  190.249094] Nextcloud[2479]: �[0;1;38:5:185m{"reqId":"Evl982FOpd0eudwaJCIE","level":2,"time":"2024-09-16T01:32:26+00:00","remoteAddr":"","user":"--","app":"appstoreFetcher","method":"","url":"--","message":"{\"Exception\":\"OCP\\\\Http\\\\Client\\\\LocalServerException\",\"Message\":\"No DNS record found for apps.nextcloud.com\",\"Code\":0,\"Trace\":[{\"file\":\"/nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/3rdparty/guzzlehttp/guzzle/src/PrepareBodyMiddleware.php\",\"line\":35,\"function\":\"OC\\\\Http\\\\Client\\\\{closure}\",\"class\":\"OC\\\\Http\\\\Client\\\\DnsPinMiddleware\",\"type\":\"->\",\"args\":[\"*…
�[2mserver # [  190.305582] Nextcloud[2479]: �[0;1;38:5:185m{"reqId":"Evl982FOpd0eudwaJCIE","level":2,"time":"2024-09-16T01:32:26+00:00","remoteAddr":"","user":"--","app":"appstoreFetcher","method":"","url":"--","message":"Could not get apps from the appstore","userAgent":"--","version":"28.0.10.1","data":{"app":"appstoreFetcher"}}�[0m�[0m
�[2mserver # [  192.349829] Nextcloud[2479]: �[0;1;31m{"reqId":"Evl982FOpd0eudwaJCIE","level":3,"time":"2024-09-16T01:32:28+00:00","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"dns_get_record(): A temporary server error occurred. at /nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/lib/private/Http/Client/DnsPinMiddleware.php#111","userAgent":"--","version":"28.0.10.1","data":{"app":"PHP"}}�[0m�[0m
�[2mserver # [  192.360380] Nextcloud[2479]: �[0;1;31m{"reqId":"Evl982FOpd0eudwaJCIE","level":3,"time":"2024-09-16T01:32:28+00:00","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"dns_get_record(): A temporary server error occurred. at /nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/lib/private/Http/Client/DnsPinMiddleware.php#111","userAgent":"--","version":"28.0.10.1","data":{"app":"PHP"}}�[0m�[0m
�[2mserver # [  192.368275] Nextcloud[2479]: �[0;1;31m{"reqId":"Evl982FOpd0eudwaJCIE","level":3,"time":"2024-09-16T01:32:28+00:00","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"dns_get_record(): A temporary server error occurred. at /nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/lib/private/Http/Client/DnsPinMiddleware.php#111","userAgent":"--","version":"28.0.10.1","data":{"app":"PHP"}}�[0m�[0m
�[2mserver # [  192.378069] Nextcloud[2479]: �[0;1;31m{"reqId":"Evl982FOpd0eudwaJCIE","level":3,"time":"2024-09-16T01:32:28+00:00","remoteAddr":"","user":"--","app":"PHP","method":"","url":"--","message":"dns_get_record(): A temporary server error occurred. at /nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/lib/private/Http/Client/DnsPinMiddleware.php#111","userAgent":"--","version":"28.0.10.1","data":{"app":"PHP"}}�[0m�[0m
�[2mserver # [  198.060554] Nextcloud[2479]: �[0;1;31m{"reqId":"Evl982FOpd0eudwaJCIE","level":3,"time":"2024-09-16T01:32:34+00:00","remoteAddr":"","user":"--","app":"core","method":"","url":"--","message":"{\"Exception\":\"OCP\\\\Files\\\\NotFoundException\",\"Message\":\"/appdata_ocpb3emz7en4/theming/global\",\"Code\":0,\"Trace\":[{\"file\":\"/nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/lib/private/Files/Node/LazyFolder.php\",\"line\":161,\"function\":\"get\",\"class\":\"OC\\\\Files\\\\Node\\\\Root\",\"type\":\"->\",\"args\":[\"/appdata_ocpb3emz7en4/theming/global\"]},{\"file\":\"/nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-…
�[2mserver # [  198.671499] systemd[1]: nextcloud-cron.service: Deactivated successfully.�[0m
�[2mserver # [  198.675160] systemd[1]: nextcloud-cron.service: Consumed 9.939s CPU time, 56.6M memory peak, 1.1K outgoing IP traffic.�[0m
�[2mserver # [  198.999592] nextcloud-update-db-start[2470]: oc_filecache table updated successfully.�[0m
�[2mserver # [  199.007698] nextcloud-update-db-start[2470]: Adding additional systag_by_objectid index to the oc_systemtag_object_mapping table, this can take some time...�[0m
�[2mserver # [  203.711615] nextcloud-update-db-start[2470]: oc_systemtag_object_mapping table updated successfully.�[0m
(finished: subtest: cron job succeeds, in 27.34 seconds)
�[1m�[32msubtest: fails with incorrect authentication�[0m
client: �[1m�[32mmust fail: curl -f -s --location -X PROPFIND -H "Depth: 1"  -u root:other  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 https://n.example.com/remote.php/dav/files/root/�[0m
�[2mserver # [  207.988063] Nextcloud[2444]: �[0;1;38:5:185m{"reqId":"ECkdUEJNmA3i1lXhuH3l","level":2,"time":"2024-09-16T01:32:43+00:00","remoteAddr":"2001:db8:1::1","user":"--","app":"core","method":"PROPFIND","url":"/remote.php/dav/files/root/","message":"Login failed: 'root' (Remote IP: '2001:db8:1::1')","userAgent":"curl/8.9.1","version":"28.0.10.1","data":{"app":"core"}}�[0m�[0m
(finished: must fail: curl -f -s --location -X PROPFIND -H "Depth: 1"  -u root:other  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 https://n.example.com/remote.php/dav/files/root/, in 4.39 seconds)
client: �[1m�[32mmust fail: curl -f -s --location -X PROPFIND -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 https://n.example.com/remote.php/dav/files/other/�[0m
�[2mserver # [  212.419280] nextcloud-update-db-start[2505]: Done.�[0m
�[2mserver # [  212.458536] systemd[1]: nextcloud-update-db.service: Deactivated successfully.�[0m
�[2mserver # [  212.461078] systemd[1]: nextcloud-update-db.service: Consumed 26.297s CPU time, 58.3M memory peak.�[0m
(finished: must fail: curl -f -s --location -X PROPFIND -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 https://n.example.com/remote.php/dav/files/other/, in 5.21 seconds)
(finished: subtest: fails with incorrect authentication, in 9.60 seconds)
�[1m�[32msubtest: fails with incorrect path�[0m
client: �[1m�[32mmust fail: curl -f -s --location -X PROPFIND -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 https://n.example.com/remote.php/dav/files/other/�[0m
(finished: must fail: curl -f -s --location -X PROPFIND -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 https://n.example.com/remote.php/dav/files/other/, in 2.52 seconds)
(finished: subtest: fails with incorrect path, in 2.52 seconds)
�[1m�[32msubtest: can access webdav�[0m
client: �[1m�[32mmust succeed: curl -f -s --location -X PROPFIND -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 https://n.example.com/remote.php/dav/files/root/�[0m
�[2mserver # [  218.499912] Nextcloud[2444]: �[0;1;38:5:185m{"reqId":"lD9KbtDI7XGB0qSTUqhm","level":2,"time":"2024-09-16T01:32:54+00:00","remoteAddr":"2001:db8:1::1","user":"root","app":"no app in context","method":"PROPFIND","url":"/remote.php/dav/files/root/","message":"{\"Exception\":\"OCP\\\\Files\\\\StorageNotAvailableException\",\"Message\":\"Local storage path does not exist \\\"/srv/nextcloud/root/\\\"\",\"Code\":1,\"Trace\":[{\"file\":\"/nix/store/i76a12xmqzir9q4cpsz212w7cpyx9mjv-nextcloud-28.0.10/lib/private/Files/Storage/Wrapper/Wrapper.php\",\"line\":340,\"function\":\"free_space\",\"class\":\"OC\\\\Files\\\\Storage\\\\FailedStor…
(finished: must succeed: curl -f -s --location -X PROPFIND -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 https://n.example.com/remote.php/dav/files/root/, in 2.64 seconds)
(finished: subtest: can access webdav, in 2.64 seconds)
�[1m�[32msubtest: can create and retrieve file�[0m
client: �[1m�[32mmust fail: curl -f -s --location -X GET -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 -T file  https://n.example.com/remote.php/dav/files/root/file�[0m
�[2mclient # curl: cannot open 'file'�[0m
�[2mclient # curl: try 'curl --help' for more information�[0m
(finished: must fail: curl -f -s --location -X GET -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 -T file  https://n.example.com/remote.php/dav/files/root/file, in 0.04 seconds)
client: �[1m�[32mmust succeed: echo 'hello' > file�[0m
(finished: must succeed: echo 'hello' > file, in 0.01 seconds)
client: �[1m�[32mmust succeed: curl -f -s --location -X PUT -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 -T file  https://n.example.com/remote.php/dav/files/root/�[0m
(finished: must succeed: curl -f -s --location -X PUT -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 -T file  https://n.example.com/remote.php/dav/files/root/, in 3.53 seconds)
client: �[1m�[32mmust succeed: curl -f -s --location -X GET -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 -T file  https://n.example.com/remote.php/dav/files/root/file�[0m
(finished: must succeed: curl -f -s --location -X GET -H "Depth: 1"  -u root:rootpw  --connect-to n.example.com:443:server:443 --connect-to n.example.com:80:server:80 -T file  https://n.example.com/remote.php/dav/files/root/file, in 2.50 seconds)
(finished: subtest: can create and retrieve file, in 6.08 seconds)
(finished: subtest: extraScript, in 48.18 seconds)
(finished: run the VM test script, in 225.44 seconds)
test script finished in 225.49s
�[1m�[32mcleanup�[0m
kill machine (pid 8)
qemu-kvm: terminating on signal 15 from pid 5 (/nix/store/h3i0acpmr8mrjx07519xxmidv8mpax4y-python3-3.12.5/bin/python3.12)
kill machine (pid 29)
qemu-kvm: terminating on signal 15 from pid 5 (/nix/store/h3i0acpmr8mrjx07519xxmidv8mpax4y-python3-3.12.5/bin/python3.12)
(finished: cleanup, in 0.02 seconds)
kill vlan (pid 6)