* Package: app-admin/github-backup-utils-2.15.1 * Repository: gentoo * Maintainer: wizardedit@gentoo.org williamh@gentoo.org * USE: abi_x86_64 amd64 elibc_glibc kernel_linux test userland_GNU * FEATURES: network-sandbox preserve-libs sandbox test userpriv usersandbox >>> Unpacking source... >>> Unpacking github-backup-utils-2.15.1.tar.gz to /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work >>> Source unpacked in /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work >>> Preparing source in /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1 ... >>> Source prepared. >>> Configuring source in /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1 ... >>> Source configured. >>> Compiling source in /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1 ... >>> Source compiled. >>> Test phase: app-admin/github-backup-utils-2.15.1 make -j1 test This is github/backup-utils rsync version 3.1.3 protocol version 31 ==> Running testsuite with GHE_TEST_REMOTE_VERSION=2.13.0 test: shellcheck: reports no errors or warnings ... OK (0s) test: ghe-ssh-config returns config for multiple nodes ... OK (0s) test: shellopts: set -e set on all scripts ... OK (0s) test: ghe-ssh simple command works ... OK (0s) test: ghe-ssh-config multiplexing disabled ... OK (0s) test: ghe-ssh complex command works ... OK (1s) test: ghe-ssh-config with extra SSH opts ... OK (1s) test: ghe-ssh when complex command given to simple form ... OK (0s) test: ghe-restore-snapshot-path reports an error when current symlink doesn't exist ... OK (0s) test: ghe-restore-snapshot-path reports an error when specified snapshot doesn't exist ... OK (0s) test: ghe-prune-snapshots using default GHE_NUM_SNAPSHOTS ... OK (1s) test: ghe-prune-snapshots non-numeric GHE_NUM_SNAPSHOTS ... OK (0s) test: ghe-detect-leaked-ssh-keys check -h displays help message ... OK (0s) test: ghe-prune-snapshots with no expired snapshots ... OK (0s) test: ghe-detect-leaked-ssh-keys clean snapshot test, no messages ... OK (0s) test: ghe-prune-snapshots with expired snapshots ... OK (0s) test: ghe-detect-leaked-ssh-keys leaked keys in current backup ... OK (0s) test: ghe-prune-snapshots incomplete snapshot pruning ... OK (1s) test: ghe-detect-leaked-ssh-keys leaked keys in old snapshot ... OK (1s) test: ghe-cluster-nodes should return both uuids for git-server ... OK (1s) test: ghe-host-check ... OK (2s) test: ghe-cluster-nodes should return one uuid for a single node ... OK (0s) test: ghe-backup-config GHE_DATA_DIR defined ... OK (0s) test: ghe-backup-config GHE_CREATE_DATA_DIR disabled ... OK (0s) test: ghe-backup-config run on GHE appliance ... OK (0s) test: ghe-backup-config ssh_host_part ... OK (0s) test: ghe-backup-config ssh_port_part ... OK (0s) test: ghe-backup-config ghe_parse_remote_version v2.x series ... OK (0s) test: ghe-backup-config verbose log redirects to file ... OK (0s) Docker is not installed or running on this host test: ghe-host-check with host arg ... OK (2s) test: ghe-host-check honours --version flag ... OK (0s) test: ghe-host-check honours --help and -h flags ... OK (0s) test: ghe-host-check detects unsupported GitHub Enterprise versions ... OK (5s) test: ghe-host-check detects high availability replica ... OK (2s) test: ghe-host-check blocks restore to old release ... OK (0s) test: ghe-restore into configured vm ... OK (15s) test: ghe-backup first snapshot ... OK (14s) test: ghe-restore logs the benchmark ... OK (11s) test: ghe-restore aborts without user verification ... OK (1s) test: ghe-backup subsequent snapshot ... OK (14s) test: ghe-restore accepts user verification ... OK (12s) test: ghe-backup logs the benchmark ... OK (13s) test: ghe-restore -c into unconfigured vm ... OK (10s) test: ghe-backup with relative data dir path ... OK (14s) test: ghe-backup fails fast when old style run in progress ... OK (1s) test: ghe-restore into unconfigured vm ... OK (12s) test: ghe-backup cleans up stale in-progress file ... OK (13s) test: ghe-restore with host arg ... OK (12s) test: ghe-restore no host arg or configured restore host ... OK (0s) test: ghe-restore with no pages backup ... OK (11s) test: ghe-backup without management console password ... OK (13s) test: ghe-restore cluster backup to non-cluster appliance ... OK (1s) test: ghe-restore no leaked ssh host keys detected ... OK (1s) test: ghe-restore with current backup leaked key detection ... ... FAILED (1s) sed: couldn't write 83 items to stdout: Broken pipe ++ date +%s + before_time=1547682074 + set -e + cat + tar -cf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/data/current/ssh-host-keys.tar --directory=/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/data ssh_host_dsa_key.pub + export FINGERPRINT_BLACKLIST=98:d8:99:d3:be:c0:55:05:db:b0:53:2f:1f:ad:b3:60 + FINGERPRINT_BLACKLIST=98:d8:99:d3:be:c0:55:05:db:b0:53:2f:1f:ad:b3:60 ++ ghe-restore -v -f localhost + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-60ea0191"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes localhost -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/configured'\'' ]' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-60ea0191"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes localhost -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/cluster'\'' ]' Error: Snapshot from a GitHub Enterprise cluster cannot be restored to a standalone appliance. Aborting. + output='Checking for leaked keys in the backup snapshot that is being restored ... * Leaked key found in current backup snapshot. * Snapshot file: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/data/1/ssh-host-keys.tar * Key file: ssh_host_dsa_key.pub * Key: 98:d8:99:d3:be:c0:55:05:db:b0:53:2f:1f:ad:b3:60 * The current backup contains leaked SSH host keys. * Current backup directory: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/data/1 * We strongly recommend rolling your SSH host keys and making a new backup. * Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. * (An upgrade may be required) * One or more older backup snapshots contain leaked SSH host keys. * No immediate action is needed but when you use one of these older snapshots for a restore, * please make sure to roll the SSH host keys after restore. * Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. * (An upgrade may be required) Connect localhost:22 OK (v2.13.0)' + true + echo 'fake ghe-export-ssh-host-keys data' + grep -q 'Leaked key found in current backup snapshot' + echo Checking for leaked keys in the backup snapshot that is being restored ... 0 1 info Leaked key found in current backup snapshot. 0 1 info Snapshot file: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/data/1/ssh-host-keys.tar 0 1 info Key file: ssh_host_dsa_key.pub 0 1 info Key: 98:d8:99:d3:be:c0:55:05:db:b0:53:2f:1f:ad:b3:60 0 1 info The current backup contains leaked SSH host keys. 0 1 info Current backup directory: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/data/1 0 1 info We strongly recommend rolling your SSH host keys and making a new backup. 0 1 info Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. 0 1 info '(An' upgrade may be 'required)' 0 1 info One or more older backup snapshots contain leaked SSH host keys. 0 1 info No immediate action is needed but when you use one of these older snapshots for a restore, 0 1 info please make sure to roll the SSH host keys after restore. 0 1 info Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. 0 1 info '(An' upgrade may be 'required)' Connect localhost:22 OK '(v2.13.0)' + grep -q 'The snapshot that is being restored contains a leaked SSH host key.' + echo Checking for leaked keys in the backup snapshot that is being restored ... 0 1 info Leaked key found in current backup snapshot. 0 1 info Snapshot file: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/data/1/ssh-host-keys.tar 0 1 info Key file: ssh_host_dsa_key.pub 0 1 info Key: 98:d8:99:d3:be:c0:55:05:db:b0:53:2f:1f:ad:b3:60 0 1 info The current backup contains leaked SSH host keys. 0 1 info Current backup directory: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/data/1 0 1 info We strongly recommend rolling your SSH host keys and making a new backup. 0 1 info Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. 0 1 info '(An' upgrade may be 'required)' 0 1 info One or more older backup snapshots contain leaked SSH host keys. 0 1 info No immediate action is needed but when you use one of these older snapshots for a restore, 0 1 info please make sure to roll the SSH host keys after restore. 0 1 info Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. 0 1 info '(An' upgrade may be 'required)' Connect localhost:22 OK '(v2.13.0)' test failed. last command exited with 1 ++ date +%s + after_time=1547682075 + elapsed_time=1 test: ghe-restore fails when restore to an active HA pair ... OK (1s) test: ghe-restore honours --version flag ... OK (0s) test: ghe-restore honours --help and -h flags ... OK (0s) test: ghe-restore exits early on unsupported version ... OK (1s) test: ghe-restore cluster ... ... FAILED (7s) ++ date +%s + before_time=1547682077 + set -e + rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote + setup_remote_metadata + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/common + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github + setup_remote_cluster + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/cluster + echo 'fake cluster config' + echo cluster + setup_maintenance_mode configured + configured=configured + '[' -n configured ']' + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/configured + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/github/current/public/system + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/github/current/public/system/maintenance.html + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/repositories + GHE_RESTORE_HOST=127.0.0.1 + export GHE_RESTORE_HOST + '[' -x /usr/bin/parallel.moreutils ']' + ghe-restore -v -f + cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/restore-out Checking for leaked keys in the backup snapshot that is being restored ... * No leaked keys found Connect 127.0.0.1:22 OK (v2.13.0) + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/configured'\'' ]' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/cluster'\'' ]' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/repl-state'\'' ]' Starting restore of 127.0.0.1:22 with backup-utils v2.15.1 from snapshot 1 + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- logger -t backup-utils + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash fake-uuid fake-uuid1 fake-uuid2 + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- test -e /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/github/current/public/system/maintenance.html + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo dd of=/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/common/backup-utils-version >/dev/null 2>&1' Stopping cron and github-timerd ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'ghe-cluster-each -- sudo service cron stop' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'ghe-cluster-each -- sudo service github-timerd stop' Restoring MySQL database ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo mkdir -p '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/tmp'\''' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo dd of=/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/tmp/mysql.sql.gz >/dev/null 2>&1' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash ghe-import-mysql 'fake ghe-export-mysql data' OK + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/tmp/mysql.sql.gz' Restoring Redis database ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- ghe-import-redis ghe-import-redis 'fake ghe-export-redis data' OK Restoring Git repositories ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- mktemp -d -t backup-utils-restore-XXXXXX + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- test -f /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/cluster + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- ghe-cluster-each -r git -u * Adding network_path 1/nw/23/bb/4c/2345 to the list of networks to send * Adding network_path 0/nw/01/aa/3f/1234 to the list of networks to send + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- sponge /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-JTvvwq/remote_tmp_list + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-JTvvwq/remote_routes_list + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-JTvvwq/remote_routes_list * Transferring repository networks to git-server-fake-uuid ... sending incremental file list 0/ 0/nw/ 0/nw/01/ 0/nw/01/aa/ 0/nw/01/aa/3f/ 0/nw/01/aa/3f/1234/ 0/nw/01/aa/3f/1234/1234.git/ 0/nw/01/aa/3f/1234/1234.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1234.git/HEAD 0/nw/01/aa/3f/1234/1234.git/config 0/nw/01/aa/3f/1234/1234.git/description 0/nw/01/aa/3f/1234/1234.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/hooks 0/nw/01/aa/3f/1234/1234.git/index 0/nw/01/aa/3f/1234/1234.git/info/ 0/nw/01/aa/3f/1234/1234.git/info/exclude 0/nw/01/aa/3f/1234/1234.git/logs/ 0/nw/01/aa/3f/1234/1234.git/logs/HEAD 0/nw/01/aa/3f/1234/1234.git/logs/refs/ 0/nw/01/aa/3f/1234/1234.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1234.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1234.git/objects/ 0/nw/01/aa/3f/1234/1234.git/objects/13/ 0/nw/01/aa/3f/1234/1234.git/objects/13/ea7f6d5f49cac7316baa0a79671d3cd021348b 0/nw/01/aa/3f/1234/1234.git/objects/4b/ 0/nw/01/aa/3f/1234/1234.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1234.git/objects/info/ 0/nw/01/aa/3f/1234/1234.git/objects/pack/ 0/nw/01/aa/3f/1234/1234.git/refs/ 0/nw/01/aa/3f/1234/1234.git/refs/heads/ 0/nw/01/aa/3f/1234/1234.git/refs/heads/master 0/nw/01/aa/3f/1234/1234.git/refs/tags/ 0/nw/01/aa/3f/1234/1234.wiki.git/ 0/nw/01/aa/3f/1234/1234.wiki.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1234.wiki.git/HEAD 0/nw/01/aa/3f/1234/1234.wiki.git/config 0/nw/01/aa/3f/1234/1234.wiki.git/description 0/nw/01/aa/3f/1234/1234.wiki.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/hooks 0/nw/01/aa/3f/1234/1234.wiki.git/index 0/nw/01/aa/3f/1234/1234.wiki.git/info/ 0/nw/01/aa/3f/1234/1234.wiki.git/info/exclude 0/nw/01/aa/3f/1234/1234.wiki.git/logs/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/HEAD 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1234.wiki.git/objects/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/13/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/13/ea7f6d5f49cac7316baa0a79671d3cd021348b 0/nw/01/aa/3f/1234/1234.wiki.git/objects/4b/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1234.wiki.git/objects/info/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/pack/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/heads/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/heads/master 0/nw/01/aa/3f/1234/1234.wiki.git/refs/tags/ 0/nw/01/aa/3f/1234/1235.git/ 0/nw/01/aa/3f/1234/1235.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1235.git/HEAD 0/nw/01/aa/3f/1234/1235.git/config 0/nw/01/aa/3f/1234/1235.git/description 0/nw/01/aa/3f/1234/1235.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/hooks 0/nw/01/aa/3f/1234/1235.git/index 0/nw/01/aa/3f/1234/1235.git/svn.history.msgpack 0/nw/01/aa/3f/1234/1235.git/info/ 0/nw/01/aa/3f/1234/1235.git/info/exclude 0/nw/01/aa/3f/1234/1235.git/logs/ 0/nw/01/aa/3f/1234/1235.git/logs/HEAD 0/nw/01/aa/3f/1234/1235.git/logs/refs/ 0/nw/01/aa/3f/1234/1235.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1235.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1235.git/objects/ 0/nw/01/aa/3f/1234/1235.git/objects/13/ 0/nw/01/aa/3f/1234/1235.git/objects/13/ea7f6d5f49cac7316baa0a79671d3cd021348b 0/nw/01/aa/3f/1234/1235.git/objects/4b/ 0/nw/01/aa/3f/1234/1235.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1235.git/objects/info/ 0/nw/01/aa/3f/1234/1235.git/objects/pack/ 0/nw/01/aa/3f/1234/1235.git/refs/ 0/nw/01/aa/3f/1234/1235.git/refs/heads/ 0/nw/01/aa/3f/1234/1235.git/refs/heads/master 0/nw/01/aa/3f/1234/1235.git/refs/tags/ 0/nw/01/aa/3f/1234/1235.git/svn_data/ 0/nw/01/aa/3f/1234/1235.git/svn_data/property_history.msgpack sent 7,106 bytes received 874 bytes 15,960.00 bytes/sec total size is 3,172 speedup is 0.40 * Transferring repository networks to git-server-fake-uuid1 ... sending incremental file list 1/ 1/nw/ 1/nw/23/ 1/nw/23/bb/ 1/nw/23/bb/4c/ 1/nw/23/bb/4c/2345/ 1/nw/23/bb/4c/2345/broken.git/ 1/nw/23/bb/4c/2345/broken.git/COMMIT_EDITMSG 1/nw/23/bb/4c/2345/broken.git/HEAD 1/nw/23/bb/4c/2345/broken.git/config 1/nw/23/bb/4c/2345/broken.git/description 1/nw/23/bb/4c/2345/broken.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/hooks 1/nw/23/bb/4c/2345/broken.git/index 1/nw/23/bb/4c/2345/broken.git/info/ 1/nw/23/bb/4c/2345/broken.git/info/exclude 1/nw/23/bb/4c/2345/broken.git/logs/ 1/nw/23/bb/4c/2345/broken.git/logs/HEAD 1/nw/23/bb/4c/2345/broken.git/logs/refs/ 1/nw/23/bb/4c/2345/broken.git/logs/refs/heads/ 1/nw/23/bb/4c/2345/broken.git/logs/refs/heads/master 1/nw/23/bb/4c/2345/broken.git/objects/ 1/nw/23/bb/4c/2345/broken.git/objects/13/ 1/nw/23/bb/4c/2345/broken.git/objects/13/ea7f6d5f49cac7316baa0a79671d3cd021348b 1/nw/23/bb/4c/2345/broken.git/objects/4b/ 1/nw/23/bb/4c/2345/broken.git/objects/info/ 1/nw/23/bb/4c/2345/broken.git/objects/pack/ 1/nw/23/bb/4c/2345/broken.git/refs/ 1/nw/23/bb/4c/2345/broken.git/refs/heads/ 1/nw/23/bb/4c/2345/broken.git/refs/heads/master 1/nw/23/bb/4c/2345/broken.git/refs/tags/ sent 4,876 bytes received 331 bytes 10,414.00 bytes/sec total size is 4,198 speedup is 0.81 * Transferring repository networks to git-server-fake-uuid2 ... sending incremental file list sent 896 bytes received 31 bytes 1,854.00 bytes/sec total size is 1,026 speedup is 1.11 Finalizing routes + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- sponge /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-JTvvwq/remote_to_restore + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash /bin/bash: line 3: parallel: command not found + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-JTvvwq + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash fake-uuid fake-uuid1 fake-uuid2 + : ghe-restore should have exited successfully + false test failed. last command exited with 1 ++ date +%s + after_time=1547682084 + elapsed_time=7 sed: couldn't write 267 items to stdout: Broken pipe test: ghe-backup empty hookshot directory ... OK (13s) test: ghe-restore missing directories or files from source snapshot displays warning ... ... FAILED (7s) sed: couldn't write 298 items to stdout: Broken pipe ++ date +%s + before_time=1547682084 + set -e + rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote + setup_remote_metadata + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/common + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github + setup_remote_cluster + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/cluster + echo 'fake cluster config' + echo cluster + setup_maintenance_mode configured + configured=configured + '[' -n configured ']' + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/configured + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/github/current/public/system + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/github/current/public/system/maintenance.html + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/repositories + GHE_RESTORE_HOST=127.0.0.1 + export GHE_RESTORE_HOST + '[' -x /usr/bin/parallel.moreutils ']' + export GHE_DGIT_CLUSTER_RESTORE_FINALIZE_WARNING=1 + GHE_DGIT_CLUSTER_RESTORE_FINALIZE_WARNING=1 + export GHE_GIST_CLUSTER_RESTORE_FINALIZE_WARNING=1 + GHE_GIST_CLUSTER_RESTORE_FINALIZE_WARNING=1 + ghe-restore -v -f + cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/restore-out Checking for leaked keys in the backup snapshot that is being restored ... * No leaked keys found Connect 127.0.0.1:22 OK (v2.13.0) + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/configured'\'' ]' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/cluster'\'' ]' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/repl-state'\'' ]' Starting restore of 127.0.0.1:22 with backup-utils v2.15.1 from snapshot 1 + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- logger -t backup-utils + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash fake-uuid fake-uuid1 fake-uuid2 + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- test -e /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/github/current/public/system/maintenance.html + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo dd of=/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/common/backup-utils-version >/dev/null 2>&1' Stopping cron and github-timerd ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'ghe-cluster-each -- sudo service cron stop' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'ghe-cluster-each -- sudo service github-timerd stop' Restoring MySQL database ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo mkdir -p '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/tmp'\''' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo dd of=/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/tmp/mysql.sql.gz >/dev/null 2>&1' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash ghe-import-mysql 'fake ghe-export-mysql data' OK + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/data/user/tmp/mysql.sql.gz' Restoring Redis database ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- ghe-import-redis ghe-import-redis 'fake ghe-export-redis data' OK Restoring Git repositories ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- mktemp -d -t backup-utils-restore-XXXXXX + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- test -f /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/remote/etc/github/cluster + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- ghe-cluster-each -r git -u * Adding network_path 1/nw/23/bb/4c/2345 to the list of networks to send * Adding network_path 0/nw/01/aa/3f/1234 to the list of networks to send + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- sponge /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-s9UaHh/remote_tmp_list + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-s9UaHh/remote_routes_list + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-s9UaHh/remote_routes_list * Transferring repository networks to git-server-fake-uuid ... sending incremental file list 0/ 0/nw/ 0/nw/01/ 0/nw/01/aa/ 0/nw/01/aa/3f/ 0/nw/01/aa/3f/1234/ 0/nw/01/aa/3f/1234/1234.git/ 0/nw/01/aa/3f/1234/1234.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1234.git/HEAD 0/nw/01/aa/3f/1234/1234.git/config 0/nw/01/aa/3f/1234/1234.git/description 0/nw/01/aa/3f/1234/1234.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/hooks 0/nw/01/aa/3f/1234/1234.git/index 0/nw/01/aa/3f/1234/1234.git/info/ 0/nw/01/aa/3f/1234/1234.git/info/exclude 0/nw/01/aa/3f/1234/1234.git/logs/ 0/nw/01/aa/3f/1234/1234.git/logs/HEAD 0/nw/01/aa/3f/1234/1234.git/logs/refs/ 0/nw/01/aa/3f/1234/1234.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1234.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1234.git/objects/ 0/nw/01/aa/3f/1234/1234.git/objects/13/ 0/nw/01/aa/3f/1234/1234.git/objects/13/ea7f6d5f49cac7316baa0a79671d3cd021348b 0/nw/01/aa/3f/1234/1234.git/objects/4b/ 0/nw/01/aa/3f/1234/1234.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1234.git/objects/info/ 0/nw/01/aa/3f/1234/1234.git/objects/pack/ 0/nw/01/aa/3f/1234/1234.git/refs/ 0/nw/01/aa/3f/1234/1234.git/refs/heads/ 0/nw/01/aa/3f/1234/1234.git/refs/heads/master 0/nw/01/aa/3f/1234/1234.git/refs/tags/ 0/nw/01/aa/3f/1234/1234.wiki.git/ 0/nw/01/aa/3f/1234/1234.wiki.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1234.wiki.git/HEAD 0/nw/01/aa/3f/1234/1234.wiki.git/config 0/nw/01/aa/3f/1234/1234.wiki.git/description 0/nw/01/aa/3f/1234/1234.wiki.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/hooks 0/nw/01/aa/3f/1234/1234.wiki.git/index 0/nw/01/aa/3f/1234/1234.wiki.git/info/ 0/nw/01/aa/3f/1234/1234.wiki.git/info/exclude 0/nw/01/aa/3f/1234/1234.wiki.git/logs/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/HEAD 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1234.wiki.git/objects/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/13/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/13/ea7f6d5f49cac7316baa0a79671d3cd021348b 0/nw/01/aa/3f/1234/1234.wiki.git/objects/4b/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1234.wiki.git/objects/info/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/pack/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/heads/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/heads/master 0/nw/01/aa/3f/1234/1234.wiki.git/refs/tags/ 0/nw/01/aa/3f/1234/1235.git/ 0/nw/01/aa/3f/1234/1235.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1235.git/HEAD 0/nw/01/aa/3f/1234/1235.git/config 0/nw/01/aa/3f/1234/1235.git/description 0/nw/01/aa/3f/1234/1235.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/hooks 0/nw/01/aa/3f/1234/1235.git/index 0/nw/01/aa/3f/1234/1235.git/svn.history.msgpack 0/nw/01/aa/3f/1234/1235.git/info/ 0/nw/01/aa/3f/1234/1235.git/info/exclude 0/nw/01/aa/3f/1234/1235.git/logs/ 0/nw/01/aa/3f/1234/1235.git/logs/HEAD 0/nw/01/aa/3f/1234/1235.git/logs/refs/ 0/nw/01/aa/3f/1234/1235.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1235.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1235.git/objects/ 0/nw/01/aa/3f/1234/1235.git/objects/13/ 0/nw/01/aa/3f/1234/1235.git/objects/13/ea7f6d5f49cac7316baa0a79671d3cd021348b 0/nw/01/aa/3f/1234/1235.git/objects/4b/ 0/nw/01/aa/3f/1234/1235.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1235.git/objects/info/ 0/nw/01/aa/3f/1234/1235.git/objects/pack/ 0/nw/01/aa/3f/1234/1235.git/refs/ 0/nw/01/aa/3f/1234/1235.git/refs/heads/ 0/nw/01/aa/3f/1234/1235.git/refs/heads/master 0/nw/01/aa/3f/1234/1235.git/refs/tags/ 0/nw/01/aa/3f/1234/1235.git/svn_data/ 0/nw/01/aa/3f/1234/1235.git/svn_data/property_history.msgpack sent 7,106 bytes received 874 bytes 15,960.00 bytes/sec total size is 3,172 speedup is 0.40 * Transferring repository networks to git-server-fake-uuid1 ... sending incremental file list 1/ 1/nw/ 1/nw/23/ 1/nw/23/bb/ 1/nw/23/bb/4c/ 1/nw/23/bb/4c/2345/ 1/nw/23/bb/4c/2345/broken.git/ 1/nw/23/bb/4c/2345/broken.git/COMMIT_EDITMSG 1/nw/23/bb/4c/2345/broken.git/HEAD 1/nw/23/bb/4c/2345/broken.git/config 1/nw/23/bb/4c/2345/broken.git/description 1/nw/23/bb/4c/2345/broken.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-47/hooks 1/nw/23/bb/4c/2345/broken.git/index 1/nw/23/bb/4c/2345/broken.git/info/ 1/nw/23/bb/4c/2345/broken.git/info/exclude 1/nw/23/bb/4c/2345/broken.git/logs/ 1/nw/23/bb/4c/2345/broken.git/logs/HEAD 1/nw/23/bb/4c/2345/broken.git/logs/refs/ 1/nw/23/bb/4c/2345/broken.git/logs/refs/heads/ 1/nw/23/bb/4c/2345/broken.git/logs/refs/heads/master 1/nw/23/bb/4c/2345/broken.git/objects/ 1/nw/23/bb/4c/2345/broken.git/objects/13/ 1/nw/23/bb/4c/2345/broken.git/objects/13/ea7f6d5f49cac7316baa0a79671d3cd021348b 1/nw/23/bb/4c/2345/broken.git/objects/4b/ 1/nw/23/bb/4c/2345/broken.git/objects/info/ 1/nw/23/bb/4c/2345/broken.git/objects/pack/ 1/nw/23/bb/4c/2345/broken.git/refs/ 1/nw/23/bb/4c/2345/broken.git/refs/heads/ 1/nw/23/bb/4c/2345/broken.git/refs/heads/master 1/nw/23/bb/4c/2345/broken.git/refs/tags/ sent 4,876 bytes received 331 bytes 10,414.00 bytes/sec total size is 4,198 speedup is 0.81 * Transferring repository networks to git-server-fake-uuid2 ... sending incremental file list sent 896 bytes received 31 bytes 1,854.00 bytes/sec total size is 1,026 speedup is 1.11 Finalizing routes + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- sponge /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-s9UaHh/remote_to_restore + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash /bin/bash: line 3: parallel: command not found + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-s9UaHh + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash fake-uuid fake-uuid1 fake-uuid2 + : ghe-restore should have exited successfully + false test failed. last command exited with 1 ++ date +%s + after_time=1547682091 + elapsed_time=7 test: ghe-backup empty git-hooks directory ... OK (11s) test: ghe-backup fsck ... OK (36s) test: ghe-backup stores version when not run from a clone ... OK (0s) test: ghe-backup with leaked SSH host key detection for current backup ... OK (9s) test: ghe-backup with no leaked keys ... OK (10s) test: ghe-backup honours --version flag ... OK (0s) test: ghe-backup honours --help and -h flags ... OK (0s) test: ghe-backup exits early on unsupported version ... OK (0s) test: ghe-backup-strategy returns rsync for HA backup ... OK (1s) test: ghe-backup cluster ... OK (11s) test: ghe-backup not missing directories or files on source appliance ... OK (12s) test: ghe-backup missing directories or files on source appliance ... OK (10s) ==> Running testsuite with GHE_TEST_REMOTE_VERSION=2.15.0 test: shellcheck: reports no errors or warnings ... OK (0s) test: shellopts: set -e set on all scripts ... OK (0s) test: ghe-ssh-config returns config for multiple nodes ... OK (0s) test: ghe-ssh simple command works ... OK (0s) test: ghe-ssh-config multiplexing disabled ... OK (0s) test: ghe-ssh complex command works ... OK (0s) test: ghe-ssh-config with extra SSH opts ... OK (1s) test: ghe-prune-snapshots using default GHE_NUM_SNAPSHOTS ... OK (1s) test: ghe-ssh when complex command given to simple form ... OK (1s) test: ghe-prune-snapshots non-numeric GHE_NUM_SNAPSHOTS ... OK (0s) test: ghe-restore-snapshot-path reports an error when current symlink doesn't exist ... OK (0s) test: ghe-restore-snapshot-path reports an error when specified snapshot doesn't exist ... OK (0s) test: ghe-prune-snapshots with no expired snapshots ... OK (0s) test: ghe-detect-leaked-ssh-keys check -h displays help message ... OK (0s) test: ghe-detect-leaked-ssh-keys clean snapshot test, no messages ... OK (0s) test: ghe-prune-snapshots with expired snapshots ... OK (1s) test: ghe-detect-leaked-ssh-keys leaked keys in current backup ... OK (1s) test: ghe-prune-snapshots incomplete snapshot pruning ... OK (0s) test: ghe-detect-leaked-ssh-keys leaked keys in old snapshot ... OK (0s) test: ghe-cluster-nodes should return both uuids for git-server ... OK (1s) test: ghe-host-check ... OK (2s) test: ghe-cluster-nodes should return one uuid for a single node ... OK (1s) test: ghe-backup-config GHE_DATA_DIR defined ... OK (0s) test: ghe-backup-config GHE_CREATE_DATA_DIR disabled ... OK (0s) test: ghe-backup-config run on GHE appliance ... OK (1s) test: ghe-backup-config ssh_host_part ... OK (0s) test: ghe-backup-config ssh_port_part ... OK (0s) test: ghe-backup-config ghe_parse_remote_version v2.x series ... OK (0s) test: ghe-backup-config verbose log redirects to file ... OK (0s) Docker is not installed or running on this host test: ghe-host-check with host arg ... OK (2s) test: ghe-host-check honours --version flag ... OK (0s) test: ghe-host-check honours --help and -h flags ... OK (0s) test: ghe-host-check detects unsupported GitHub Enterprise versions ... OK (5s) test: ghe-host-check detects high availability replica ... OK (2s) test: ghe-host-check blocks restore to old release ... OK (0s) test: ghe-restore into configured vm ... OK (15s) test: ghe-backup first snapshot ... OK (16s) test: ghe-restore logs the benchmark ... OK (11s) test: ghe-restore aborts without user verification ... OK (1s) test: ghe-backup subsequent snapshot ... OK (13s) test: ghe-restore accepts user verification ... OK (11s) test: ghe-backup logs the benchmark ... OK (14s) test: ghe-restore -c into unconfigured vm ... OK (12s) test: ghe-backup with relative data dir path ... OK (14s) test: ghe-backup fails fast when old style run in progress ... OK (1s) test: ghe-restore into unconfigured vm ... OK (11s) test: ghe-restore with host arg ... OK (12s) test: ghe-backup cleans up stale in-progress file ... OK (13s) test: ghe-restore no host arg or configured restore host ... OK (0s) test: ghe-restore with no pages backup ... OK (11s) test: ghe-restore cluster backup to non-cluster appliance ... OK (1s) test: ghe-backup without management console password ... OK (12s) test: ghe-restore no leaked ssh host keys detected ... OK (1s) test: ghe-restore with current backup leaked key detection ... ... FAILED (1s) ++ date +%s + before_time=1547682273 + set -e + cat + tar -cf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/data/current/ssh-host-keys.tar --directory=/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/data ssh_host_dsa_key.pub + export FINGERPRINT_BLACKLIST=98:d8:99:d3:be:c0:55:05:db:b0:53:2f:1f:ad:b3:60 + FINGERPRINT_BLACKLIST=98:d8:99:d3:be:c0:55:05:db:b0:53:2f:1f:ad:b3:60 ++ ghe-restore -v -f localhost + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-60ea0191"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes localhost -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/configured'\'' ]' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-60ea0191"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes localhost -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/cluster'\'' ]' Error: Snapshot from a GitHub Enterprise cluster cannot be restored to a standalone appliance. Aborting. + output='Checking for leaked keys in the backup snapshot that is being restored ... * Leaked key found in current backup snapshot. * Snapshot file: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/data/1/ssh-host-keys.tar * Key file: ssh_host_dsa_key.pub * Key: 98:d8:99:d3:be:c0:55:05:db:b0:53:2f:1f:ad:b3:60 * The current backup contains leaked SSH host keys. * Current backup directory: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/data/1 * We strongly recommend rolling your SSH host keys and making a new backup. * Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. * (An upgrade may be required) * One or more older backup snapshots contain leaked SSH host keys. * No immediate action is needed but when you use one of these older snapshots for a restore, * please make sure to roll the SSH host keys after restore. * Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. * (An upgrade may be required) Connect localhost:22 OK (v2.15.0)' + true + echo 'fake ghe-export-ssh-host-keys data' + echo Checking for leaked keys in the backup snapshot that is being restored ... 0 1 info Leaked key found in current backup snapshot. 0 1 info Snapshot file: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/data/1/ssh-host-keys.tar 0 1 info Key file: ssh_host_dsa_key.pub 0 1 info Key: 98:d8:99:d3:be:c0:55:05:db:b0:53:2f:1f:ad:b3:60 0 1 info The current backup contains leaked SSH host keys. 0 1 info Current backup directory: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/data/1 0 1 info We strongly recommend rolling your SSH host keys and making a new backup. 0 1 info Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. 0 1 info '(An' upgrade may be 'required)' 0 1 info One or more older backup snapshots contain leaked SSH host keys. 0 1 info No immediate action is needed but when you use one of these older snapshots for a restore, 0 1 info please make sure to roll the SSH host keys after restore. 0 1 info Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. 0 1 info '(An' upgrade may be 'required)' Connect localhost:22 OK '(v2.15.0)' + grep -q 'Leaked key found in current backup snapshot' + grep -q 'The snapshot that is being restored contains a leaked SSH host key.' + echo Checking for leaked keys in the backup snapshot that is being restored ... 0 1 info Leaked key found in current backup snapshot. 0 1 info Snapshot file: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/data/1/ssh-host-keys.tar 0 1 info Key file: ssh_host_dsa_key.pub 0 1 info Key: 98:d8:99:d3:be:c0:55:05:db:b0:53:2f:1f:ad:b3:60 0 1 info The current backup contains leaked SSH host keys. 0 1 info Current backup directory: /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/data/1 0 1 info We strongly recommend rolling your SSH host keys and making a new backup. 0 1 info Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. 0 1 info '(An' upgrade may be 'required)' 0 1 info One or more older backup snapshots contain leaked SSH host keys. 0 1 info No immediate action is needed but when you use one of these older snapshots for a restore, 0 1 info please make sure to roll the SSH host keys after restore. 0 1 info Roll the keys either manually or with ghe-ssh-roll-host-keys on the appliance. 0 1 info '(An' upgrade may be 'required)' Connect localhost:22 OK '(v2.15.0)' test failed. last command exited with 1 ++ date +%s + after_time=1547682274 + elapsed_time=1 sed: couldn't write 83 items to stdout: Broken pipe test: ghe-restore fails when restore to an active HA pair ... OK (1s) test: ghe-restore honours --version flag ... OK (0s) test: ghe-restore honours --help and -h flags ... OK (1s) test: ghe-restore exits early on unsupported version ... OK (0s) test: ghe-restore cluster ... ... FAILED (7s) ++ date +%s + before_time=1547682277 + set -e + rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote + setup_remote_metadata + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/common + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github + setup_remote_cluster + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/cluster + echo 'fake cluster config' + echo cluster + setup_maintenance_mode configured + configured=configured + '[' -n configured ']' + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/configured + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/github/current/public/system + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/github/current/public/system/maintenance.html + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/repositories + GHE_RESTORE_HOST=127.0.0.1 + export GHE_RESTORE_HOST + '[' -x /usr/bin/parallel.moreutils ']' + ghe-restore -v -f + cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/restore-out Checking for leaked keys in the backup snapshot that is being restored ... * No leaked keys found Connect 127.0.0.1:22 OK (v2.15.0) + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/configured'\'' ]' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/cluster'\'' ]' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/repl-state'\'' ]' Starting restore of 127.0.0.1:22 with backup-utils v2.15.1 from snapshot 1 + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- logger -t backup-utils + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash fake-uuid fake-uuid1 fake-uuid2 + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- test -e /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/github/current/public/system/maintenance.html + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo dd of=/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/common/backup-utils-version >/dev/null 2>&1' Stopping cron and github-timerd ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'ghe-cluster-each -- sudo service cron stop' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'ghe-cluster-each -- sudo service github-timerd stop' Restoring MySQL database ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo mkdir -p '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/tmp'\''' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo dd of=/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/tmp/mysql.sql.gz >/dev/null 2>&1' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash ghe-import-mysql 'fake ghe-export-mysql data' OK + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/tmp/mysql.sql.gz' Restoring Redis database ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- ghe-import-redis ghe-import-redis 'fake ghe-export-redis data' OK Restoring Git repositories ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- mktemp -d -t backup-utils-restore-XXXXXX + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- test -f /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/cluster + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- ghe-cluster-each -r git -u * Adding network_path 1/nw/23/bb/4c/2345 to the list of networks to send * Adding network_path 0/nw/01/aa/3f/1234 to the list of networks to send + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- sponge /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-PszFPo/remote_tmp_list + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-PszFPo/remote_routes_list + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-PszFPo/remote_routes_list * Transferring repository networks to git-server-fake-uuid ... sending incremental file list 0/ 0/nw/ 0/nw/01/ 0/nw/01/aa/ 0/nw/01/aa/3f/ 0/nw/01/aa/3f/1234/ 0/nw/01/aa/3f/1234/1234.git/ 0/nw/01/aa/3f/1234/1234.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1234.git/HEAD 0/nw/01/aa/3f/1234/1234.git/config 0/nw/01/aa/3f/1234/1234.git/description 0/nw/01/aa/3f/1234/1234.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/hooks 0/nw/01/aa/3f/1234/1234.git/index 0/nw/01/aa/3f/1234/1234.git/info/ 0/nw/01/aa/3f/1234/1234.git/info/exclude 0/nw/01/aa/3f/1234/1234.git/logs/ 0/nw/01/aa/3f/1234/1234.git/logs/HEAD 0/nw/01/aa/3f/1234/1234.git/logs/refs/ 0/nw/01/aa/3f/1234/1234.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1234.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1234.git/objects/ 0/nw/01/aa/3f/1234/1234.git/objects/24/ 0/nw/01/aa/3f/1234/1234.git/objects/24/7baa174e2f22369b0662080be8a8382f0ee111 0/nw/01/aa/3f/1234/1234.git/objects/4b/ 0/nw/01/aa/3f/1234/1234.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1234.git/objects/info/ 0/nw/01/aa/3f/1234/1234.git/objects/pack/ 0/nw/01/aa/3f/1234/1234.git/refs/ 0/nw/01/aa/3f/1234/1234.git/refs/heads/ 0/nw/01/aa/3f/1234/1234.git/refs/heads/master 0/nw/01/aa/3f/1234/1234.git/refs/tags/ 0/nw/01/aa/3f/1234/1234.wiki.git/ 0/nw/01/aa/3f/1234/1234.wiki.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1234.wiki.git/HEAD 0/nw/01/aa/3f/1234/1234.wiki.git/config 0/nw/01/aa/3f/1234/1234.wiki.git/description 0/nw/01/aa/3f/1234/1234.wiki.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/hooks 0/nw/01/aa/3f/1234/1234.wiki.git/index 0/nw/01/aa/3f/1234/1234.wiki.git/info/ 0/nw/01/aa/3f/1234/1234.wiki.git/info/exclude 0/nw/01/aa/3f/1234/1234.wiki.git/logs/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/HEAD 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1234.wiki.git/objects/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/24/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/24/7baa174e2f22369b0662080be8a8382f0ee111 0/nw/01/aa/3f/1234/1234.wiki.git/objects/4b/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1234.wiki.git/objects/info/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/pack/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/heads/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/heads/master 0/nw/01/aa/3f/1234/1234.wiki.git/refs/tags/ 0/nw/01/aa/3f/1234/1235.git/ 0/nw/01/aa/3f/1234/1235.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1235.git/HEAD 0/nw/01/aa/3f/1234/1235.git/config 0/nw/01/aa/3f/1234/1235.git/description 0/nw/01/aa/3f/1234/1235.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/hooks 0/nw/01/aa/3f/1234/1235.git/index 0/nw/01/aa/3f/1234/1235.git/svn.history.msgpack 0/nw/01/aa/3f/1234/1235.git/info/ 0/nw/01/aa/3f/1234/1235.git/info/exclude 0/nw/01/aa/3f/1234/1235.git/logs/ 0/nw/01/aa/3f/1234/1235.git/logs/HEAD 0/nw/01/aa/3f/1234/1235.git/logs/refs/ 0/nw/01/aa/3f/1234/1235.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1235.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1235.git/objects/ 0/nw/01/aa/3f/1234/1235.git/objects/24/ 0/nw/01/aa/3f/1234/1235.git/objects/24/7baa174e2f22369b0662080be8a8382f0ee111 0/nw/01/aa/3f/1234/1235.git/objects/4b/ 0/nw/01/aa/3f/1234/1235.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1235.git/objects/info/ 0/nw/01/aa/3f/1234/1235.git/objects/pack/ 0/nw/01/aa/3f/1234/1235.git/refs/ 0/nw/01/aa/3f/1234/1235.git/refs/heads/ 0/nw/01/aa/3f/1234/1235.git/refs/heads/master 0/nw/01/aa/3f/1234/1235.git/refs/tags/ 0/nw/01/aa/3f/1234/1235.git/svn_data/ 0/nw/01/aa/3f/1234/1235.git/svn_data/property_history.msgpack sent 7,041 bytes received 874 bytes 15,830.00 bytes/sec total size is 3,181 speedup is 0.40 * Transferring repository networks to git-server-fake-uuid1 ... sending incremental file list 1/ 1/nw/ 1/nw/23/ 1/nw/23/bb/ 1/nw/23/bb/4c/ 1/nw/23/bb/4c/2345/ 1/nw/23/bb/4c/2345/broken.git/ 1/nw/23/bb/4c/2345/broken.git/COMMIT_EDITMSG 1/nw/23/bb/4c/2345/broken.git/HEAD 1/nw/23/bb/4c/2345/broken.git/config 1/nw/23/bb/4c/2345/broken.git/description 1/nw/23/bb/4c/2345/broken.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/hooks 1/nw/23/bb/4c/2345/broken.git/index 1/nw/23/bb/4c/2345/broken.git/info/ 1/nw/23/bb/4c/2345/broken.git/info/exclude 1/nw/23/bb/4c/2345/broken.git/logs/ 1/nw/23/bb/4c/2345/broken.git/logs/HEAD 1/nw/23/bb/4c/2345/broken.git/logs/refs/ 1/nw/23/bb/4c/2345/broken.git/logs/refs/heads/ 1/nw/23/bb/4c/2345/broken.git/logs/refs/heads/master 1/nw/23/bb/4c/2345/broken.git/objects/ 1/nw/23/bb/4c/2345/broken.git/objects/24/ 1/nw/23/bb/4c/2345/broken.git/objects/24/7baa174e2f22369b0662080be8a8382f0ee111 1/nw/23/bb/4c/2345/broken.git/objects/4b/ 1/nw/23/bb/4c/2345/broken.git/objects/info/ 1/nw/23/bb/4c/2345/broken.git/objects/pack/ 1/nw/23/bb/4c/2345/broken.git/refs/ 1/nw/23/bb/4c/2345/broken.git/refs/heads/ 1/nw/23/bb/4c/2345/broken.git/refs/heads/master 1/nw/23/bb/4c/2345/broken.git/refs/tags/ sent 4,809 bytes received 331 bytes 10,280.00 bytes/sec total size is 4,210 speedup is 0.82 sed: couldn't write 267 items to stdout: Broken pipe * Transferring repository networks to git-server-fake-uuid2 ... sending incremental file list sent 890 bytes received 31 bytes 614.00 bytes/sec total size is 1,029 speedup is 1.12 Finalizing routes + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- sponge /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-PszFPo/remote_to_restore + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash /bin/bash: line 3: parallel: command not found + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-PszFPo + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash fake-uuid fake-uuid1 fake-uuid2 + : ghe-restore should have exited successfully + false test failed. last command exited with 1 ++ date +%s + after_time=1547682284 + elapsed_time=7 test: ghe-backup empty hookshot directory ... OK (14s) test: ghe-restore missing directories or files from source snapshot displays warning ... ... FAILED (6s) sed: couldn't write 280 items to stdout: Broken pipe ++ date +%s + before_time=1547682284 + set -e + rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote + setup_remote_metadata + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/common + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github + setup_remote_cluster + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/cluster + echo 'fake cluster config' + echo cluster + setup_maintenance_mode configured + configured=configured + '[' -n configured ']' + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/configured + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/github/current/public/system + touch /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/github/current/public/system/maintenance.html + mkdir -p /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/repositories + GHE_RESTORE_HOST=127.0.0.1 + export GHE_RESTORE_HOST + '[' -x /usr/bin/parallel.moreutils ']' + export GHE_DGIT_CLUSTER_RESTORE_FINALIZE_WARNING=1 + GHE_DGIT_CLUSTER_RESTORE_FINALIZE_WARNING=1 + export GHE_GIST_CLUSTER_RESTORE_FINALIZE_WARNING=1 + GHE_GIST_CLUSTER_RESTORE_FINALIZE_WARNING=1 + ghe-restore -v -f + cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/restore-out Checking for leaked keys in the backup snapshot that is being restored ... * No leaked keys found Connect 127.0.0.1:22 OK (v2.15.0) + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/configured'\'' ]' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/cluster'\'' ]' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- '[ -f '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/repl-state'\'' ]' Starting restore of 127.0.0.1:22 with backup-utils v2.15.1 from snapshot 1 + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- logger -t backup-utils + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash fake-uuid fake-uuid1 fake-uuid2 + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- test -e /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/github/current/public/system/maintenance.html + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo dd of=/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/common/backup-utils-version >/dev/null 2>&1' Stopping cron and github-timerd ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'ghe-cluster-each -- sudo service cron stop' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'ghe-cluster-each -- sudo service github-timerd stop' Restoring MySQL database ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo mkdir -p '\''/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/tmp'\''' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo dd of=/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/tmp/mysql.sql.gz >/dev/null 2>&1' + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash ghe-import-mysql 'fake ghe-export-mysql data' OK + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- 'sudo rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/data/user/tmp/mysql.sql.gz' Restoring Redis database ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- ghe-import-redis ghe-import-redis 'fake ghe-export-redis data' OK Restoring Git repositories ... + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- mktemp -d -t backup-utils-restore-XXXXXX + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- test -f /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/remote/etc/github/cluster + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- ghe-cluster-each -r git -u * Adding network_path 1/nw/23/bb/4c/2345 to the list of networks to send * Adding network_path 0/nw/01/aa/3f/1234 to the list of networks to send + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- sponge /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-WfnUKS/remote_tmp_list + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-WfnUKS/remote_routes_list + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- cat /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-WfnUKS/remote_routes_list * Transferring repository networks to git-server-fake-uuid ... sending incremental file list 0/ 0/nw/ 0/nw/01/ 0/nw/01/aa/ 0/nw/01/aa/3f/ 0/nw/01/aa/3f/1234/ 0/nw/01/aa/3f/1234/1234.git/ 0/nw/01/aa/3f/1234/1234.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1234.git/HEAD 0/nw/01/aa/3f/1234/1234.git/config 0/nw/01/aa/3f/1234/1234.git/description 0/nw/01/aa/3f/1234/1234.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/hooks 0/nw/01/aa/3f/1234/1234.git/index 0/nw/01/aa/3f/1234/1234.git/info/ 0/nw/01/aa/3f/1234/1234.git/info/exclude 0/nw/01/aa/3f/1234/1234.git/logs/ 0/nw/01/aa/3f/1234/1234.git/logs/HEAD 0/nw/01/aa/3f/1234/1234.git/logs/refs/ 0/nw/01/aa/3f/1234/1234.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1234.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1234.git/objects/ 0/nw/01/aa/3f/1234/1234.git/objects/24/ 0/nw/01/aa/3f/1234/1234.git/objects/24/7baa174e2f22369b0662080be8a8382f0ee111 0/nw/01/aa/3f/1234/1234.git/objects/4b/ 0/nw/01/aa/3f/1234/1234.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1234.git/objects/info/ 0/nw/01/aa/3f/1234/1234.git/objects/pack/ 0/nw/01/aa/3f/1234/1234.git/refs/ 0/nw/01/aa/3f/1234/1234.git/refs/heads/ 0/nw/01/aa/3f/1234/1234.git/refs/heads/master 0/nw/01/aa/3f/1234/1234.git/refs/tags/ 0/nw/01/aa/3f/1234/1234.wiki.git/ 0/nw/01/aa/3f/1234/1234.wiki.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1234.wiki.git/HEAD 0/nw/01/aa/3f/1234/1234.wiki.git/config 0/nw/01/aa/3f/1234/1234.wiki.git/description 0/nw/01/aa/3f/1234/1234.wiki.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/hooks 0/nw/01/aa/3f/1234/1234.wiki.git/index 0/nw/01/aa/3f/1234/1234.wiki.git/info/ 0/nw/01/aa/3f/1234/1234.wiki.git/info/exclude 0/nw/01/aa/3f/1234/1234.wiki.git/logs/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/HEAD 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1234.wiki.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1234.wiki.git/objects/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/24/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/24/7baa174e2f22369b0662080be8a8382f0ee111 0/nw/01/aa/3f/1234/1234.wiki.git/objects/4b/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1234.wiki.git/objects/info/ 0/nw/01/aa/3f/1234/1234.wiki.git/objects/pack/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/heads/ 0/nw/01/aa/3f/1234/1234.wiki.git/refs/heads/master 0/nw/01/aa/3f/1234/1234.wiki.git/refs/tags/ 0/nw/01/aa/3f/1234/1235.git/ 0/nw/01/aa/3f/1234/1235.git/COMMIT_EDITMSG 0/nw/01/aa/3f/1234/1235.git/HEAD 0/nw/01/aa/3f/1234/1235.git/config 0/nw/01/aa/3f/1234/1235.git/description 0/nw/01/aa/3f/1234/1235.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/hooks 0/nw/01/aa/3f/1234/1235.git/index 0/nw/01/aa/3f/1234/1235.git/svn.history.msgpack 0/nw/01/aa/3f/1234/1235.git/info/ 0/nw/01/aa/3f/1234/1235.git/info/exclude 0/nw/01/aa/3f/1234/1235.git/logs/ 0/nw/01/aa/3f/1234/1235.git/logs/HEAD 0/nw/01/aa/3f/1234/1235.git/logs/refs/ 0/nw/01/aa/3f/1234/1235.git/logs/refs/heads/ 0/nw/01/aa/3f/1234/1235.git/logs/refs/heads/master 0/nw/01/aa/3f/1234/1235.git/objects/ 0/nw/01/aa/3f/1234/1235.git/objects/24/ 0/nw/01/aa/3f/1234/1235.git/objects/24/7baa174e2f22369b0662080be8a8382f0ee111 0/nw/01/aa/3f/1234/1235.git/objects/4b/ 0/nw/01/aa/3f/1234/1235.git/objects/4b/825dc642cb6eb9a060e54bf8d69288fbee4904 0/nw/01/aa/3f/1234/1235.git/objects/info/ 0/nw/01/aa/3f/1234/1235.git/objects/pack/ 0/nw/01/aa/3f/1234/1235.git/refs/ 0/nw/01/aa/3f/1234/1235.git/refs/heads/ 0/nw/01/aa/3f/1234/1235.git/refs/heads/master 0/nw/01/aa/3f/1234/1235.git/refs/tags/ 0/nw/01/aa/3f/1234/1235.git/svn_data/ 0/nw/01/aa/3f/1234/1235.git/svn_data/property_history.msgpack sent 7,041 bytes received 874 bytes 15,830.00 bytes/sec total size is 3,181 speedup is 0.40 * Transferring repository networks to git-server-fake-uuid1 ... sending incremental file list 1/ 1/nw/ 1/nw/23/ 1/nw/23/bb/ 1/nw/23/bb/4c/ 1/nw/23/bb/4c/2345/ 1/nw/23/bb/4c/2345/broken.git/ 1/nw/23/bb/4c/2345/broken.git/COMMIT_EDITMSG 1/nw/23/bb/4c/2345/broken.git/HEAD 1/nw/23/bb/4c/2345/broken.git/config 1/nw/23/bb/4c/2345/broken.git/description 1/nw/23/bb/4c/2345/broken.git/hooks -> /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/test-ghe-restore.sh-12554/hooks 1/nw/23/bb/4c/2345/broken.git/index 1/nw/23/bb/4c/2345/broken.git/info/ 1/nw/23/bb/4c/2345/broken.git/info/exclude 1/nw/23/bb/4c/2345/broken.git/logs/ 1/nw/23/bb/4c/2345/broken.git/logs/HEAD 1/nw/23/bb/4c/2345/broken.git/logs/refs/ 1/nw/23/bb/4c/2345/broken.git/logs/refs/heads/ 1/nw/23/bb/4c/2345/broken.git/logs/refs/heads/master 1/nw/23/bb/4c/2345/broken.git/objects/ 1/nw/23/bb/4c/2345/broken.git/objects/24/ 1/nw/23/bb/4c/2345/broken.git/objects/24/7baa174e2f22369b0662080be8a8382f0ee111 1/nw/23/bb/4c/2345/broken.git/objects/4b/ 1/nw/23/bb/4c/2345/broken.git/objects/info/ 1/nw/23/bb/4c/2345/broken.git/objects/pack/ 1/nw/23/bb/4c/2345/broken.git/refs/ 1/nw/23/bb/4c/2345/broken.git/refs/heads/ 1/nw/23/bb/4c/2345/broken.git/refs/heads/master 1/nw/23/bb/4c/2345/broken.git/refs/tags/ sent 4,809 bytes received 331 bytes 10,280.00 bytes/sec total size is 4,210 speedup is 0.82 * Transferring repository networks to git-server-fake-uuid2 ... sending incremental file list sent 890 bytes received 31 bytes 614.00 bytes/sec total size is 1,029 speedup is 1.12 Finalizing routes + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- sponge /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-WfnUKS/remote_to_restore + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash /bin/bash: line 3: parallel: command not found + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- rm -rf /var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/backup-utils-restore-WfnUKS + exec ssh -o ControlMaster=auto -o 'ControlPath="/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1/test/tmp/.ghe-sshmux-544c4229"' -o ControlPersist=10m -o ServerAliveInterval=10 -l admin -p 22 -o BatchMode=yes 127.0.0.1 -- /bin/bash fake-uuid fake-uuid1 fake-uuid2 + : ghe-restore should have exited successfully + false test failed. last command exited with 1 ++ date +%s + after_time=1547682290 + elapsed_time=6 test: ghe-backup empty git-hooks directory ... OK (10s) test: ghe-backup fsck ... OK (38s) test: ghe-backup stores version when not run from a clone ... OK (0s) test: ghe-backup with leaked SSH host key detection for current backup ... OK (10s) test: ghe-backup with no leaked keys ... OK (10s) test: ghe-backup honours --version flag ... OK (0s) test: ghe-backup honours --help and -h flags ... OK (0s) test: ghe-backup exits early on unsupported version ... OK (0s) test: ghe-backup-strategy returns rsync for HA backup ... OK (1s) test: ghe-backup cluster ... OK (11s) test: ghe-backup not missing directories or files on source appliance ... OK (11s) test: ghe-backup missing directories or files on source appliance ... OK (11s) make: *** [Makefile:4: test] Error 1 * ERROR: app-admin/github-backup-utils-2.15.1::gentoo failed (test phase): * emake failed * * If you need support, post the output of `emerge --info '=app-admin/github-backup-utils-2.15.1::gentoo'`, * the complete build log and the output of `emerge -pqv '=app-admin/github-backup-utils-2.15.1::gentoo'`. * The complete build log is located at '/var/log/portage/app-admin:github-backup-utils-2.15.1:20190116-233943.log'. * For convenience, a symlink to the build log is located at '/var/tmp/portage/app-admin/github-backup-utils-2.15.1/temp/build.log'. * The ebuild environment file is located at '/var/tmp/portage/app-admin/github-backup-utils-2.15.1/temp/environment'. * Working directory: '/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1' * S: '/var/tmp/portage/app-admin/github-backup-utils-2.15.1/work/backup-utils-2.15.1'