http://d-jenkins.ubuntu-ci:8080/view/Vivid/view/BootTest/job/vivid-boottest-unity-scopes-api/1/console looks similar but I have a traceback (running with -d helps ?): FailedConsole Output Started by remote host 10.100.0.2 Building remotely on krillin-09 in workspace /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api [vivid-boottest-unity-scopes-api] $ /bin/bash -x /tmp/hudson5448376527591332470.sh + set +e + rm -Rf '*' + rm -r test-runner rm: cannot remove 'test-runner': No such file or directory + true + setup_branch='lp:~canonical-ci-engineering/ubuntu-test-cases/boottest' + bzr_cmd='bzr branch lp:~canonical-ci-engineering/ubuntu-test-cases/boottest test-runner' + bzr branch 'lp:~canonical-ci-engineering/ubuntu-test-cases/boottest' test-runner Branched 405 revisions. + annotate-output test-runner/scripts/boottest.sh vivid unity-scopes-api krillin-09 12:36:28 I: Started test-runner/scripts/boottest.sh vivid unity-scopes-api krillin-09 12:36:28 E: +++ readlink -f test-runner/scripts/boottest.sh 12:36:28 E: ++ dirname /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/boottest.sh 12:36:28 E: + BASEDIR=/var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/.. 12:36:28 E: + export RELEASE=vivid 12:36:28 E: + RELEASE=vivid 12:36:28 E: + export SRC_PKG_NAME=unity-scopes-api 12:36:28 E: + SRC_PKG_NAME=unity-scopes-api 12:36:28 E: + export NODE_NAME=krillin-09 12:36:28 E: + NODE_NAME=krillin-09 12:36:28 E: + export ADT_TIMEOUT=600 12:36:28 E: + ADT_TIMEOUT=600 12:36:28 E: + export RSYNC_DEST=rsync://tachash.ubuntu-ci/boottest/ 12:36:28 E: + RSYNC_DEST=rsync://tachash.ubuntu-ci/boottest/ 12:36:28 E: + '[' -z krillin-09 ']' 12:36:28 E: ++ /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../scripts/get-adb-id krillin-09 12:36:28 E: + export ANDROID_SERIAL=JW010053 12:36:28 E: + ANDROID_SERIAL=JW010053 12:36:28 E: + export VERSION=1.2.51-0ubuntu3 12:36:28 E: + VERSION=1.2.51-0ubuntu3 12:36:28 E: + BOOTTESTRC=/var/lib/jenkins/.ubuntu-ci/boottest.rc 12:36:28 E: + '[' -f /var/lib/jenkins/.ubuntu-ci/boottest.rc ']' 12:36:28 E: + ARCH=krillin 12:36:28 E: + PHABLET_PASSWORD=0000 12:36:28 E: + export SKIP_CLICK=1 12:36:28 E: + SKIP_CLICK=1 12:36:28 E: + export SKIP_TESTCONFIG=1 12:36:28 E: + SKIP_TESTCONFIG=1 12:36:28 E: + '[' -z krillin-09 ']' 12:36:28 E: + /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../scripts/recover.py krillin-09 12:36:28 O: /home/phablet 12:36:28 O: 0 12:36:28 E: + TESTS=/var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../tests 12:36:28 E: + REVISION=0 12:36:28 E: + PROV_CMD='/var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../scripts/provision.sh -r 0 -n /var/lib/jenkins/.ubuntu-ci/wifi.conf -w' 12:36:28 E: + '[' -z krillin-09 ']' 12:36:28 E: + /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../scripts/provision.sh -r 0 -n /var/lib/jenkins/.ubuntu-ci/wifi.conf -w -s JW010053 12:36:28 E: + '[' -d /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/clientlogs ']' 12:36:28 E: + mkdir -p /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/clientlogs 12:36:28 E: + '[' -z ']' 12:36:28 O: = Wed Feb 11 12:36:28 UTC 2015: FLASHING DEVICE 12:36:28 E: + full_flash 12:36:28 E: + log 'FLASHING DEVICE' 12:36:28 E: ++ date 12:36:28 E: + echo = Wed Feb 11 12:36:28 UTC 2015: FLASHING DEVICE 12:36:28 E: ++ get-device-type 12:36:29 E: + DEVICE_TYPE=krillin 12:36:29 E: + retry 60 3 reboot_bootloader 12:36:29 O: = Wed Feb 11 12:36:29 UTC 2015: Attempting adb reboot bootloader 12:36:29 E: + timeout=60 12:36:29 E: + shift 12:36:29 E: + loops=3 12:36:29 E: + shift 12:36:29 E: + cmd=reboot_bootloader 12:36:29 E: + loopcnt=0 12:36:29 E: + true 12:36:29 E: + reboot_bootloader 12:36:29 E: + log 'Attempting adb reboot bootloader' 12:36:29 E: ++ date 12:36:29 E: + echo = Wed Feb 11 12:36:29 UTC 2015: Attempting adb reboot bootloader 12:36:29 E: + adb reboot bootloader 12:36:29 E: + '[' -n JW010053 ']' 12:36:29 E: + sleep 30 12:36:59 E: + fastboot devices 12:36:59 E: + grep -q JW010053 12:36:59 E: + return 0 12:36:59 E: + break 12:36:59 E: + RECOVERY= 12:36:59 O: = Wed Feb 11 12:36:59 UTC 2015: The following wget is only needed for some devices. If it fails, it's probably safe to ignore 12:36:59 E: + mkdir -p recovery 12:36:59 E: + log 'The following wget is only needed for some devices. If it fails, it'\''s probably safe to ignore' 12:36:59 E: ++ date 12:36:59 E: + echo = Wed Feb 11 12:36:59 UTC 2015: The following wget is only needed for some devices. If it fails, 'it'\''s' probably safe to ignore 12:36:59 E: + wget -P recovery http://people.canonical.com/~plars/touch/recovery-krillin.img 12:36:59 E: --2015-02-11 12:36:59-- http://people.canonical.com/~plars/touch/recovery-krillin.img 12:36:59 E: Resolving people.canonical.com (people.canonical.com)... 91.189.89.62 12:36:59 E: Connecting to people.canonical.com (people.canonical.com)|91.189.89.62|:80... connected. 12:36:59 E: HTTP request sent, awaiting response... 200 OK 12:36:59 E: Length: 8595456 (8.2M) 12:36:59 E: Saving to: 'recovery/recovery-krillin.img' 12:36:59 E: 12:37:00 E: 0K .......... .......... .......... .......... .......... 0% 312K 27s 12:37:00 E: 50K .......... .......... .......... .......... .......... 1% 312K 27s 12:37:00 E: 100K .......... .......... .......... .......... .......... 1% 315K 26s 12:37:00 E: 150K .......... .......... .......... .......... .......... 2% 624K 23s 12:37:00 E: 200K .......... .......... .......... .......... .......... 2% 611K 21s 12:37:00 E: 250K .......... .......... .......... .......... .......... 3% 622K 19s 12:37:00 E: 300K .......... .......... .......... .......... .......... 4% 623K 18s 12:37:00 E: 350K .......... .......... .......... .......... .......... 4% 622K 18s 12:37:00 E: 400K .......... .......... .......... .......... .......... 5% 619K 17s 12:37:01 E: 450K .......... .......... .......... .......... .......... 5% 623K 16s 12:37:01 E: 500K .......... .......... .......... .......... .......... 6% 623K 16s 12:37:01 E: 550K .......... .......... .......... .......... .......... 7% 624K 16s 12:37:01 E: 600K .......... .......... .......... .......... .......... 7% 624K 15s 12:37:01 E: 650K .......... .......... .......... .......... .......... 8% 52.4M 14s 12:37:01 E: 700K .......... .......... .......... .......... .......... 8% 623K 14s 12:37:01 E: 750K .......... .......... .......... .......... .......... 9% 624K 14s 12:37:01 E: 800K .......... .......... .......... .......... .......... 10% 622K 14s 12:37:01 E: 850K .......... .......... .......... .......... .......... 10% 80.6M 13s 12:37:01 E: 900K .......... .......... .......... .......... .......... 11% 623K 13s 12:37:01 E: 950K .......... .......... .......... .......... .......... 11% 624K 12s 12:37:01 E: 1000K .......... .......... .......... .......... .......... 12% 631K 12s 12:37:01 E: 1050K .......... .......... .......... .......... .......... 13% 1.63M 12s 12:37:01 E: 1100K .......... .......... .......... .......... .......... 13% 976K 12s 12:37:01 E: 1150K .......... .......... .......... .......... .......... 14% 629K 12s 12:37:01 E: 1200K .......... .......... .......... .......... .......... 14% 1.64M 11s 12:37:02 E: 1250K .......... .......... .......... .......... .......... 15% 622K 11s 12:37:02 E: 1300K .......... .......... .......... .......... .......... 16% 72.3M 11s 12:37:02 E: 1350K .......... .......... .......... .......... .......... 16% 625K 11s 12:37:02 E: 1400K .......... .......... .......... .......... .......... 17% 627K 11s 12:37:02 E: 1450K .......... .......... .......... .......... .......... 17% 46.2M 10s 12:37:02 E: 1500K .......... .......... .......... .......... .......... 18% 632K 10s 12:37:02 E: 1550K .......... .......... .......... .......... .......... 19% 47.6M 10s 12:37:02 E: 1600K .......... .......... .......... .......... .......... 19% 636K 10s 12:37:02 E: 1650K .......... .......... .......... .......... .......... 20% 31.7M 9s 12:37:02 E: 1700K .......... .......... .......... .......... .......... 20% 636K 9s 12:37:02 E: 1750K .......... .......... .......... .......... .......... 21% 22.8M 9s 12:37:02 E: 1800K .......... .......... .......... .......... .......... 22% 644K 9s 12:37:02 E: 1850K .......... .......... .......... .......... .......... 22% 20.0M 9s 12:37:02 E: 1900K .......... .......... .......... .......... .......... 23% 32.0M 8s 12:37:02 E: 1950K .......... .......... .......... .......... .......... 23% 631K 8s 12:37:02 E: 2000K .......... .......... .......... .......... .......... 24% 42.8M 8s 12:37:02 E: 2050K .......... .......... .......... .......... .......... 25% 635K 8s 12:37:02 E: 2100K .......... .......... .......... .......... .......... 25% 38.9M 8s 12:37:02 E: 2150K .......... .......... .......... .......... .......... 26% 633K 8s 12:37:02 E: 2200K .......... .......... .......... .......... .......... 26% 52.4M 8s 12:37:02 E: 2250K .......... .......... .......... .......... .......... 27% 45.6M 7s 12:37:02 E: 2300K .......... .......... .......... .......... .......... 27% 631K 7s 12:37:02 E: 2350K .......... .......... .......... .......... .......... 28% 612K 7s 12:37:02 E: 2400K .......... .......... .......... .......... .......... 29% 131M 7s 12:37:02 E: 2450K .......... .......... .......... .......... .......... 29% 241M 7s 12:37:02 E: 2500K .......... .......... .......... .......... .......... 30% 637K 7s 12:37:02 E: 2550K .......... .......... .......... .......... .......... 30% 42.6M 7s 12:37:03 E: 2600K .......... .......... .......... .......... .......... 31% 427K 7s 12:37:03 E: 2650K .......... .......... .......... .......... .......... 32% 626K 7s 12:37:03 E: 2700K .......... .......... .......... .......... .......... 32% 1.14M 7s 12:37:03 E: 2750K .......... .......... .......... .......... .......... 33% 625K 7s 12:37:03 E: 2800K .......... .......... .......... .......... .......... 33% 640K 7s 12:37:03 E: 2850K .......... .......... .......... .......... .......... 34% 1.24M 7s 12:37:03 E: 2900K .......... .......... .......... .......... .......... 35% 1.14M 6s 12:37:03 E: 2950K .......... .......... .......... .......... .......... 35% 1.30M 6s 12:37:03 E: 3000K .......... .......... .......... .......... .......... 36% 1.14M 6s 12:37:03 E: 3050K .......... .......... .......... .......... .......... 36% 1.02M 6s 12:37:03 E: 3100K .......... .......... .......... .......... .......... 37% 1.51M 6s 12:37:03 E: 3150K .......... .......... .......... .......... .......... 38% 1.10M 6s 12:37:03 E: 3200K .......... .......... .......... .......... .......... 38% 1.37M 6s 12:37:03 E: 3250K .......... .......... .......... .......... .......... 39% 1.11M 6s 12:37:03 E: 3300K .......... .......... .......... .......... .......... 39% 1.34M 6s 12:37:03 E: 3350K .......... .......... .......... .......... .......... 40% 1.11M 6s 12:37:03 E: 3400K .......... .......... .......... .......... .......... 41% 1.35M 6s 12:37:03 E: 3450K .......... .......... .......... .......... .......... 41% 1.12M 5s 12:37:03 E: 3500K .......... .......... .......... .......... .......... 42% 687K 5s 12:37:04 E: 3550K .......... .......... .......... .......... .......... 42% 6.63M 5s 12:37:04 E: 3600K .......... .......... .......... .......... .......... 43% 686K 5s 12:37:04 E: 3650K .......... .......... .......... .......... .......... 44% 6.71M 5s 12:37:04 E: 3700K .......... .......... .......... .......... .......... 44% 688K 5s 12:37:04 E: 3750K .......... .......... .......... .......... .......... 45% 1.11M 5s 12:37:04 E: 3800K .......... .......... .......... .......... .......... 45% 1.32M 5s 12:37:04 E: 3850K .......... .......... .......... .......... .......... 46% 1.13M 5s 12:37:04 E: 3900K .......... .......... .......... .......... .......... 47% 1.32M 5s 12:37:04 E: 3950K .......... .......... .......... .......... .......... 47% 7.82M 5s 12:37:04 E: 4000K .......... .......... .......... .......... .......... 48% 672K 5s 12:37:04 E: 4050K .......... .......... .......... .......... .......... 48% 1.13M 5s 12:37:04 E: 4100K .......... .......... .......... .......... .......... 49% 1.34M 5s 12:37:04 E: 4150K .......... .......... .......... .......... .......... 50% 7.82M 4s 12:37:04 E: 4200K .......... .......... .......... .......... .......... 50% 1.26M 4s 12:37:04 E: 4250K .......... .......... .......... .......... .......... 51% 1.16M 4s 12:37:04 E: 4300K .......... .......... .......... .......... .......... 51% 1.27M 4s 12:37:04 E: 4350K .......... .......... .......... .......... .......... 52% 1.40M 4s 12:37:04 E: 4400K .......... .......... .......... .......... .......... 53% 5.90M 4s 12:37:04 E: 4450K .......... .......... .......... .......... .......... 53% 1.28M 4s 12:37:04 E: 4500K .......... .......... .......... .......... .......... 54% 1.16M 4s 12:37:04 E: 4550K .......... .......... .......... .......... .......... 54% 11.9M 4s 12:37:04 E: 4600K .......... .......... .......... .......... .......... 55% 1.40M 4s 12:37:04 E: 4650K .......... .......... .......... .......... .......... 55% 1.16M 4s 12:37:04 E: 4700K .......... .......... .......... .......... .......... 56% 1.26M 4s 12:37:04 E: 4750K .......... .......... .......... .......... .......... 57% 1.17M 4s 12:37:04 E: 4800K .......... .......... .......... .......... .......... 57% 1.30M 4s 12:37:04 E: 4850K .......... .......... .......... .......... .......... 58% 24.6M 4s 12:37:04 E: 4900K .......... .......... .......... .......... .......... 58% 1.16M 3s 12:37:05 E: 4950K .......... .......... .......... .......... .......... 59% 1.27M 3s 12:37:05 E: 5000K .......... .......... .......... .......... .......... 60% 1.58M 3s 12:37:05 E: 5050K .......... .......... .......... .......... .......... 60% 4.33M 3s 12:37:05 E: 5100K .......... .......... .......... .......... .......... 61% 1.24M 3s 12:37:05 E: 5150K .......... .......... .......... .......... .......... 61% 1.20M 3s 12:37:05 E: 5200K .......... .......... .......... .......... .......... 62% 1.24M 3s 12:37:05 E: 5250K .......... .......... .......... .......... .......... 63% 1.67M 3s 12:37:05 E: 5300K .......... .......... .......... .......... .......... 63% 3.90M 3s 12:37:05 E: 5350K .......... .......... .......... .......... .......... 64% 1.25M 3s 12:37:05 E: 5400K .......... .......... .......... .......... .......... 64% 1.22M 3s 12:37:05 E: 5450K .......... .......... .......... .......... .......... 65% 1.37M 3s 12:37:05 E: 5500K .......... .......... .......... .......... .......... 66% 1.46M 3s 12:37:05 E: 5550K .......... .......... .......... .......... .......... 66% 4.01M 3s 12:37:05 E: 5600K .......... .......... .......... .......... .......... 67% 1.22M 3s 12:37:05 E: 5650K .......... .......... .......... .......... .......... 67% 1.73M 3s 12:37:05 E: 5700K .......... .......... .......... .......... .......... 68% 1.05M 3s 12:37:05 E: 5750K .......... .......... .......... .......... .......... 69% 7.43M 2s 12:37:05 E: 5800K .......... .......... .......... .......... .......... 69% 1.25M 2s 12:37:05 E: 5850K .......... .......... .......... .......... .......... 70% 1.19M 2s 12:37:05 E: 5900K .......... .......... .......... .......... .......... 70% 1.75M 2s 12:37:05 E: 5950K .......... .......... .......... .......... .......... 71% 1.07M 2s 12:37:05 E: 6000K .......... .......... .......... .......... .......... 72% 6.49M 2s 12:37:05 E: 6050K .......... .......... .......... .......... .......... 72% 1.32M 2s 12:37:05 E: 6100K .......... .......... .......... .......... .......... 73% 1.18M 2s 12:37:05 E: 6150K .......... .......... .......... .......... .......... 73% 1.69M 2s 12:37:05 E: 6200K .......... .......... .......... .......... .......... 74% 1.09M 2s 12:37:05 E: 6250K .......... .......... .......... .......... .......... 75% 6.95M 2s 12:37:05 E: 6300K .......... .......... .......... .......... .......... 75% 1.27M 2s 12:37:05 E: 6350K .......... .......... .......... .......... .......... 76% 1.19M 2s 12:37:05 E: 6400K .......... .......... .......... .......... .......... 76% 1.68M 2s 12:37:05 E: 6450K .......... .......... .......... .......... .......... 77% 4.68M 2s 12:37:05 E: 6500K .......... .......... .......... .......... .......... 78% 1.20M 2s 12:37:06 E: 6550K .......... .......... .......... .......... .......... 78% 1.26M 2s 12:37:06 E: 6600K .......... .......... .......... .......... .......... 79% 1.40M 2s 12:37:06 E: 6650K .......... .......... .......... .......... .......... 79% 1.40M 2s 12:37:06 E: 6700K .......... .......... .......... .......... .......... 80% 4.44M 1s 12:37:06 E: 6750K .......... .......... .......... .......... .......... 81% 1.21M 1s 12:37:06 E: 6800K .......... .......... .......... .......... .......... 81% 1.23M 1s 12:37:06 E: 6850K .......... .......... .......... .......... .......... 82% 1.42M 1s 12:37:06 E: 6900K .......... .......... .......... .......... .......... 82% 1.38M 1s 12:37:06 E: 6950K .......... .......... .......... .......... .......... 83% 4.57M 1s 12:37:06 E: 7000K .......... .......... .......... .......... .......... 83% 1.21M 1s 12:37:06 E: 7050K .......... .......... .......... .......... .......... 84% 1.65M 1s 12:37:06 E: 7100K .......... .......... .......... .......... .......... 85% 1.10M 1s 12:37:06 E: 7150K .......... .......... .......... .......... .......... 85% 7.80M 1s 12:37:06 E: 7200K .......... .......... .......... .......... .......... 86% 1.22M 1s 12:37:06 E: 7250K .......... .......... .......... .......... .......... 86% 1.20M 1s 12:37:06 E: 7300K .......... .......... .......... .......... .......... 87% 1.63M 1s 12:37:06 E: 7350K .......... .......... .......... .......... .......... 88% 1.13M 1s 12:37:06 E: 7400K .......... .......... .......... .......... .......... 88% 6.63M 1s 12:37:06 E: 7450K .......... .......... .......... .......... .......... 89% 1.27M 1s 12:37:06 E: 7500K .......... .......... .......... .......... .......... 89% 1.40M 1s 12:37:06 E: 7550K .......... .......... .......... .......... .......... 90% 1.31M 1s 12:37:06 E: 7600K .......... .......... .......... .......... .......... 91% 1.15M 1s 12:37:06 E: 7650K .......... .......... .......... .......... .......... 91% 6.55M 1s 12:37:06 E: 7700K .......... .......... .......... .......... .......... 92% 1.28M 1s 12:37:06 E: 7750K .......... .......... .......... .......... .......... 92% 1.40M 1s 12:37:06 E: 7800K .......... .......... .......... .......... .......... 93% 1.33M 0s 12:37:06 E: 7850K .......... .......... .......... .......... .......... 94% 5.19M 0s 12:37:06 E: 7900K .......... .......... .......... .......... .......... 94% 1.16M 0s 12:37:06 E: 7950K .......... .......... .......... .......... .......... 95% 1.29M 0s 12:37:06 E: 8000K .......... .......... .......... .......... .......... 95% 1.41M 0s 12:37:06 E: 8050K .......... .......... .......... .......... .......... 96% 1.36M 0s 12:37:06 E: 8100K .......... .......... .......... .......... .......... 97% 4.90M 0s 12:37:07 E: 8150K .......... .......... .......... .......... .......... 97% 1.16M 0s 12:37:07 E: 8200K .......... .......... .......... .......... .......... 98% 1.29M 0s 12:37:07 E: 8250K .......... .......... .......... .......... .......... 98% 1.39M 0s 12:37:07 E: 8300K .......... .......... .......... .......... .......... 99% 1.37M 0s 12:37:07 E: 8350K .......... .......... .......... .......... .... 100% 995K=7.2s 12:37:07 E: 12:37:07 E: 2015-02-11 12:37:07 (1.14 MB/s) - 'recovery/recovery-krillin.img' saved [8595456/8595456] 12:37:07 E: 12:37:07 E: + '[' -f recovery/recovery-krillin.img ']' 12:37:07 O: WARNING --developer-mode and --password are dangerous as they remove security features from your device 12:37:07 E: + RECOVERY=--recovery-image=recovery/recovery-krillin.img 12:37:07 E: + retry 10 3 timeout 1800 ubuntu-device-flash --revision=0 touch --recovery-image=recovery/recovery-krillin.img --password 0000 --bootstrap --developer-mode --channel ubuntu-touch/devel-proposed 12:37:07 E: + timeout=10 12:37:07 E: + shift 12:37:07 E: + loops=3 12:37:07 E: + shift 12:37:07 E: + cmd='timeout 1800 ubuntu-device-flash --revision=0 touch --recovery-image=recovery/recovery-krillin.img --password 0000 --bootstrap --developer-mode --channel ubuntu-touch/devel-proposed' 12:37:07 E: + loopcnt=0 12:37:07 E: + true 12:37:07 E: + timeout 1800 ubuntu-device-flash --revision=0 touch --recovery-image=recovery/recovery-krillin.img --password 0000 --bootstrap --developer-mode --channel ubuntu-touch/devel-proposed 12:37:07 E: 2015/02/11 12:37:07 Expecting the device to be in the bootloader... waiting 12:37:08 E: 2015/02/11 12:37:08 Device is |krillin| 12:37:08 E: 2015/02/11 12:37:08 Flashing version 106 from ubuntu-touch/devel-proposed channel and server https://system-image.ubuntu.com to device krillin 12:37:27 E: 2015/02/11 12:37:27 Start pushing /var/lib/jenkins/.cache/ubuntuimages/ubuntu-touch/devel-proposed/krillin/version-106.tar.xz to device 12:37:27 E: 2015/02/11 12:37:27 Start pushing /var/lib/jenkins/.cache/ubuntuimages/pool/custom-67e3700ec668b34a70913a23e935f72fdba164abc4098ff155ecefc9ff0c5e2e.tar.xz to device 12:37:27 E: 2015/02/11 12:37:27 Start pushing /var/lib/jenkins/.cache/ubuntuimages/pool/device-12f1e7fabd46b2e69d76bf15e9fb55f7d27c7cea6556888d62693f37aaa49364.tar.xz to device 12:37:27 E: 2015/02/11 12:37:27 Start pushing /var/lib/jenkins/.cache/ubuntuimages/pool/ubuntu-5426adf8d980cedf0b749a835ce1cf1ba64ad24f876cc069e1bc70d4336bce09.tar.xz to device 12:37:27 E: 2015/02/11 12:37:27 Start pushing /var/lib/jenkins/.cache/ubuntuimages/gpg/image-signing.tar.xz to device 12:37:27 E: 2015/02/11 12:37:27 Start pushing /var/lib/jenkins/.cache/ubuntuimages/gpg/image-master.tar.xz to device 12:37:27 E: 2015/02/11 12:37:27 Done pushing /var/lib/jenkins/.cache/ubuntuimages/ubuntu-touch/devel-proposed/krillin/version-106.tar.xz to device 12:37:27 E: 2015/02/11 12:37:27 Done pushing /var/lib/jenkins/.cache/ubuntuimages/gpg/image-signing.tar.xz to device 12:37:27 E: 2015/02/11 12:37:27 Done pushing /var/lib/jenkins/.cache/ubuntuimages/gpg/image-master.tar.xz to device 12:37:30 E: 2015/02/11 12:37:30 Done pushing /var/lib/jenkins/.cache/ubuntuimages/pool/custom-67e3700ec668b34a70913a23e935f72fdba164abc4098ff155ecefc9ff0c5e2e.tar.xz to device 12:38:10 E: 2015/02/11 12:38:10 Done pushing /var/lib/jenkins/.cache/ubuntuimages/pool/device-12f1e7fabd46b2e69d76bf15e9fb55f7d27c7cea6556888d62693f37aaa49364.tar.xz to device 12:39:54 E: 2015/02/11 12:39:54 Done pushing /var/lib/jenkins/.cache/ubuntuimages/pool/ubuntu-5426adf8d980cedf0b749a835ce1cf1ba64ad24f876cc069e1bc70d4336bce09.tar.xz to device 12:39:54 E: 2015/02/11 12:39:54 Created ubuntu_command: /var/lib/jenkins/.cache/ubuntuimages/ubuntu_commands355293066 12:39:54 E: 2015/02/11 12:39:54 Rebooting into recovery to flash 12:40:10 E: + break 12:40:10 E: + adb wait-for-device 12:44:24 E: + sleep 60 12:45:24 E: + '[' -z ']' 12:45:24 E: + log 'SETTING UP WIFI' 12:45:24 O: = Wed Feb 11 12:45:24 UTC 2015: SETTING UP WIFI 12:45:24 E: ++ date 12:45:24 E: + echo = Wed Feb 11 12:45:24 UTC 2015: SETTING UP WIFI 12:45:24 E: + retry 60 5 adb-shell 'sudo -iu phablet env |grep UPSTART_SESSION=unix' 12:45:24 E: + timeout=60 12:45:24 E: + shift 12:45:24 E: + loops=5 12:45:24 E: + shift 12:45:24 E: + cmd='adb-shell sudo -iu phablet env |grep UPSTART_SESSION=unix' 12:45:24 E: + loopcnt=0 12:45:24 E: + true 12:45:24 E: + adb-shell sudo -iu phablet env '|grep' UPSTART_SESSION=unix 12:45:24 O: UPSTART_SESSION=unix:abstract=/com/ubuntu/upstart-session/32011/1559 12:45:24 O: ADB_RC=0 12:45:24 E: + break 12:45:24 E: + retry 60 5 phablet-network -n /var/lib/jenkins/.ubuntu-ci/wifi.conf 12:45:24 E: + timeout=60 12:45:24 E: + shift 12:45:24 O: Network file is /var/lib/jenkins/.ubuntu-ci/wifi.conf 12:45:24 E: + loops=5 12:45:24 E: + shift 12:45:24 E: + cmd='phablet-network -n /var/lib/jenkins/.ubuntu-ci/wifi.conf' 12:45:24 E: + loopcnt=0 12:45:24 O: Provisioning network on device 12:45:24 E: + true 12:45:24 E: + phablet-network -n /var/lib/jenkins/.ubuntu-ci/wifi.conf 12:45:27 O: Connection with UUID '79f87c26-53f8-4952-8687-3086e3fcccd7' created and activated on device 'wlan0' 12:45:27 O: 12:45:27 O: Network setup complete 12:45:37 O: PING launchpad.net (91.189.89.223) 56(84) bytes of data. 12:45:37 E: + break 12:45:37 E: + phablet-config welcome-wizard --disable 12:45:38 E: + '[' -n ' ' ']' 12:45:38 E: + log 'CUSTOMIZING IMAGE' 12:45:38 O: = Wed Feb 11 12:45:38 UTC 2015: CUSTOMIZING IMAGE 12:45:38 E: ++ date 12:45:38 E: + echo = Wed Feb 11 12:45:38 UTC 2015: CUSTOMIZING IMAGE 12:45:38 E: + phablet-config writable-image -r 0000 12:46:12 O: PING launchpad.net (91.189.89.222) 56(84) bytes of data. 12:46:13 E: + log 'SETTING UP SUDO' 12:46:13 E: ++ date 12:46:13 O: = Wed Feb 11 12:46:13 UTC 2015: SETTING UP SUDO 12:46:13 E: + echo = Wed Feb 11 12:46:13 UTC 2015: SETTING UP SUDO 12:46:13 E: + adb shell 'echo 0000 |sudo -S bash -c '\''echo phablet ALL=\(ALL\) NOPASSWD: ALL > /etc/sudoers.d/phablet && chmod 600 /etc/sudoers.d/phablet'\''' 12:46:13 E: + adb shell 'sudo dbus-send --system --print-reply --dest=org.freedesktop.Accounts /org/freedesktop/Accounts/User32011 org.freedesktop.DBus.Properties.Set string:com.canonical.unity.AccountsService string:demo-edges variant:boolean:false' 12:46:13 O: [sudo] password for phablet: method return sender=:1.9 -> dest=:1.71 reply_serial=2 12:46:13 E: + '[' -n 1 ']' 12:46:13 O: = Wed Feb 11 12:46:13 UTC 2015: SKIPPING CLICK PACKAGE SETUP AS REQUESTED 12:46:13 E: + log 'SKIPPING CLICK PACKAGE SETUP AS REQUESTED' 12:46:13 E: ++ date 12:46:13 E: + echo = Wed Feb 11 12:46:13 UTC 2015: SKIPPING CLICK PACKAGE SETUP AS REQUESTED 12:46:13 E: + adb push /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../utils/target /home/phablet/bin 12:46:14 E: push: /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../utils/target/prepare-autopilot-test.sh -> /home/phablet/bin/prepare-autopilot-test.sh 12:46:14 E: push: /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../utils/target/autopilot-run -> /home/phablet/bin/autopilot-run 12:46:14 E: push: /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../utils/target/check-clickhook-rules -> /home/phablet/bin/check-clickhook-rules 12:46:14 E: push: /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../utils/target/autopilot-list -> /home/phablet/bin/autopilot-list 12:46:14 E: 4 files pushed. 0 files skipped. 12:46:14 E: 5 KB/s (935 bytes in 0.162s) 12:46:14 E: + image_info 12:46:14 E: ++ adb shell 'sudo system-image-cli -i | sed -n -e '\''s/version version: \([0-9]*\)/\1/p'\'' -e '\''s/version ubuntu: \([0-9]*\)/\1/p'\'' -e '\''s/version device: \([0-9]*\)/\1/p'\'' | paste -s -d:' 12:46:15 E: + IMAGEVER=$'106:20150210:20150210-95b6a9f\r' 12:46:15 E: ++ adb shell 'sudo system-image-cli -i | sed -n -e '\''s/channel: \(.*\)/\1/p'\'' | paste -s -d:' 12:46:16 E: + CHAN=$'ubuntu-touch/devel-proposed\r' 12:46:16 E: ++ echo $'106:20150210:20150210-95b6a9f\r' 12:46:16 E: ++ cut -d: -f1 12:46:16 E: + REV=106 12:46:16 E: + echo '--bootstrap --developer-mode --channel ubuntu-touch/devel-proposed' 12:46:16 E: + grep -q '\-\-revision' 12:46:16 E: + REVISION=--revision=106 12:46:16 E: + echo '--bootstrap --developer-mode --channel ubuntu-touch/devel-proposed' 12:46:16 E: + grep -q '\-\-channel' 12:46:16 E: + adb shell 'echo '\''106:20150210:20150210-95b6a9f '\'' > /home/phablet/.ci-version' 12:46:16 E: + echo 019d6c35-2240-4e31-8838-a83815c98166 12:46:16 E: + adb push /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/clientlogs/.ci-uuid /home/phablet/ 12:46:16 E: 0 KB/s (37 bytes in 0.040s) 12:46:16 E: + cat 12:46:16 E: + adb push /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/clientlogs/.ci-flash-args /home/phablet/.ci-flash-args 12:46:17 E: 1 KB/s (67 bytes in 0.040s) 12:46:17 E: + echo 12:46:17 E: + adb push /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/clientlogs/.ci-customizations /home/phablet/.ci-customizations 12:46:17 E: 0 KB/s (1 bytes in 0.038s) 12:46:17 E: + set_hwclock 12:46:17 O: = Wed Feb 11 12:46:17 UTC 2015: SETTING HWCLOCK TO CURRENT TIME 12:46:17 E: + log 'SETTING HWCLOCK TO CURRENT TIME' 12:46:17 E: ++ date 12:46:17 E: + echo = Wed Feb 11 12:46:17 UTC 2015: SETTING HWCLOCK TO CURRENT TIME 12:46:17 E: + adb-shell sudo ntpdate 91.189.94.4 12:46:23 O: 11 Feb 12:46:23 ntpdate[2876]: adjust time server 91.189.94.4 offset 0.066425 sec 12:46:23 O: ADB_RC=0 12:46:23 E: + adb-shell sudo hwclock -w 12:46:24 O: ADB_RC=0 12:46:24 E: + log 'Current date on device is:' 12:46:24 E: ++ date 12:46:24 O: = Wed Feb 11 12:46:24 UTC 2015: Current date on device is: 12:46:24 E: + echo = Wed Feb 11 12:46:24 UTC 2015: Current date on device is: 12:46:24 E: + adb shell date 12:46:24 O: Wed Feb 11 12:46:24 UTC 2015 12:46:24 E: + log 'Current hwclock on device is:' 12:46:24 E: ++ date 12:46:24 O: = Wed Feb 11 12:46:24 UTC 2015: Current hwclock on device is: 12:46:24 E: + echo = Wed Feb 11 12:46:24 UTC 2015: Current hwclock on device is: 12:46:24 E: + adb shell sudo hwclock 12:46:25 O: Wed 11 Feb 2015 12:46:25 PM UTC -0.695535 seconds 12:46:25 E: + rm -f adt-commands 12:46:25 E: + echo '(apt-get update || (sleep 15; apt-get update))' 12:46:25 E: + ADT_CMD='timeout 600 adt-run --debug --no-built-binaries' 12:46:25 E: + ADT_VIRT='adt-virt-ssh -s /usr/share/autopkgtest/ssh-setup/adb -- -s JW010053' 12:46:25 E: + ADT_OPTS='--apt-pocket=proposed --setup-commands=adt-commands --- adt-virt-ssh -s /usr/share/autopkgtest/ssh-setup/adb -- -s JW010053' 12:46:25 E: + FROM=/var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../tests/getpkgsrc/debian/tests/getpkgsrc.template 12:46:25 E: + TARGET=/var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../tests/getpkgsrc/debian/tests/getpkgsrc 12:46:25 E: + sed -e 's/{{ source_package }}/unity-scopes-api/' /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../tests/getpkgsrc/debian/tests/getpkgsrc.template 12:46:25 E: + PKG_SRC_DIR=pkgsrc 12:46:25 E: + rm -fr pkgsrc 12:46:25 E: + set +e 12:46:25 E: + timeout 600 adt-run --debug --no-built-binaries --unbuilt-tree /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../tests/getpkgsrc -o pkgsrc --apt-pocket=proposed --setup-commands=adt-commands --- adt-virt-ssh -s /usr/share/autopkgtest/ssh-setup/adb -- -s JW010053 12:46:25 E: adt-run: DBG: Parsed options: Namespace(apt_pocket=['proposed'], copy=[], gainroot=None, gnupghome='~/.cache/autopkgtest', logfile=None, output_dir='pkgsrc', set_lang='C.UTF-8', setup_commands=['adt-commands'], shell=False, shell_fail=False, summary=None, timeout_build=100000, timeout_copy=300, timeout_factor=1.0, timeout_install=3000, timeout_short=100, timeout_test=10000, user=None, verbosity=2) 12:46:25 E: adt-run: DBG: Remaining arguments: ['--no-built-binaries', '--unbuilt-tree', '/var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../tests/getpkgsrc'] 12:46:25 E: adt-run: DBG: Interpreted actions: ['--no-built-binaries', '--unbuilt-tree', '/var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../tests/getpkgsrc'] 12:46:25 E: adt-run: DBG: Virt runner arguments: ['adt-virt-ssh', '-s', '/usr/share/autopkgtest/ssh-setup/adb', '--', '-s', 'JW010053'] 12:46:25 E: adt-run: DBG: / tmp(specified) rmtree pkgsrc 12:46:25 E: adt-run: DBG: testbed init 12:46:25 E: adt-run [12:46:25]: version 3.9.4-0~1035~ubuntu14.04.1 12:46:25 E: adt-run: DBG: $ vserver: adt-virt-ssh -s /usr/share/autopkgtest/ssh-setup/adb -- -s JW010053 12:46:25 E: adt-run: DBG: got reply from testbed: ok 12:46:25 E: adt-run: DBG: testbed open, scratch=None 12:46:25 E: adt-run: DBG: sending command to testbed: open 12:46:25 E: Waiting for device ADB to appear... 12:46:26 E: Configuring Ubuntu phone for testing... 12:46:27 E: Preparing Ubuntu phone for running tests... 12:46:27 E: Waiting for desktop to boot 12:46:28 E: 9 KB/s (397 bytes in 0.040s) 12:46:28 E: 3 KB/s (143 bytes in 0.040s) 12:46:28 E: ssh_exchange_identification: read: Connection reset by peer 12:46:30 E: ssh_exchange_identification: read: Connection reset by peer 12:46:33 E: Warning: Permanently added '[localhost]:2222' (RSA) to the list of known hosts. 12:46:34 E: adt-run: DBG: got reply from testbed: ok /tmp/adt-run.d3o9Xe 12:46:34 E: adt-run: DBG: sending command to testbed: print-execute-command 12:46:35 E: adt-run: DBG: got reply from testbed: ok /tmp/adt-virt-ssh.8fnutfuj/runcmd 12:46:35 E: adt-run: DBG: sending command to testbed: capabilities 12:46:35 E: adt-run: DBG: got reply from testbed: ok isolation-machine reboot suggested-normal-user=phablet root-on-testbed 12:46:35 E: adt-run: DBG: testbed capabilities: ['isolation-machine', 'reboot', 'suggested-normal-user=phablet', 'root-on-testbed'] 12:46:35 E: adt-run [12:46:35]: @@@@@@@@@@@@@@@@@@@@ test bed setup 12:46:35 E: adt-run: DBG: testbed command ['sh', '-ec', 'awk \'/^deb(-src)? .*(ubuntu.com|debian.org|ftpmaster)/ { if ($3 !~ /-/) { $3 = $3"-proposed"; print }}\' /etc/apt/sources.list `ls /etc/apt/sources.list.d/*.list 2>/dev/null|| true` > /etc/apt/sources.list.d/proposed.list'], kind short, sout raw, serr pipe, env ['LANG=C.UTF-8'] 12:46:35 E: adt-run: DBG: testbed command exited with code 0 12:46:35 E: adt-run: DBG: testbed command ['bash', '-ec', 'for d in /boot /etc/init /etc/init.d /etc/systemd/system /lib/systemd/system; do [ ! -d $d ] || touch -r $d /tmp/adt-run.d3o9Xe/${d//\\//_}.stamp; done'], kind short, sout raw, serr pipe, env ['LANG=C.UTF-8'] 12:46:36 E: adt-run: DBG: testbed command exited with code 0 12:46:36 E: adt-run: DBG: testbed command ['sh', '-ec', '(apt-get update || (sleep 15; apt-get update))'], kind install, sout raw, serr raw, env ['ADT_NORMAL_USER=phablet', 'DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'LANG=C.UTF-8'] 12:46:36 O: Ign http://ports.ubuntu.com vivid InRelease 12:46:36 O: Ign http://ports.ubuntu.com vivid-updates InRelease 12:46:36 O: Ign http://ports.ubuntu.com vivid-security InRelease 12:46:36 O: Ign http://ports.ubuntu.com vivid-proposed InRelease 12:46:36 O: Get:1 http://ports.ubuntu.com vivid Release.gpg [933 B] 12:46:36 O: Get:2 http://ports.ubuntu.com vivid-updates Release.gpg [933 B] 12:46:36 O: Get:3 http://ports.ubuntu.com vivid-security Release.gpg [933 B] 12:46:36 O: Get:4 http://ports.ubuntu.com vivid-proposed Release.gpg [933 B] 12:46:37 O: Get:5 http://ports.ubuntu.com vivid Release [215 kB] 12:46:37 O: Get:6 http://ports.ubuntu.com vivid-updates Release [59.7 kB] 12:46:37 O: Get:7 http://ports.ubuntu.com vivid-security Release [59.7 kB] 12:46:37 O: Get:8 http://ports.ubuntu.com vivid-proposed Release [215 kB] 12:46:38 O: Get:9 http://ports.ubuntu.com vivid/main Sources [1053 kB] 12:46:38 O: Get:10 http://ports.ubuntu.com vivid/restricted Sources [5888 B] 12:46:38 O: Get:11 http://ports.ubuntu.com vivid/universe Sources [7019 kB] 12:46:42 O: Get:12 http://ports.ubuntu.com vivid/main armhf Packages [1315 kB] 12:46:43 O: Get:13 http://ports.ubuntu.com vivid/restricted armhf Packages [5003 B] 12:46:43 O: Get:14 http://ports.ubuntu.com vivid/universe armhf Packages [6300 kB] 12:46:46 O: Get:15 http://ports.ubuntu.com vivid/main Translation-en [779 kB] 12:46:46 O: Hit http://ports.ubuntu.com vivid/restricted Translation-en 12:46:46 O: Get:16 http://ports.ubuntu.com vivid/universe Translation-en [4457 kB] 12:46:49 O: Get:17 http://ports.ubuntu.com vivid-updates/main Sources [14 B] 12:46:49 O: Get:18 http://ports.ubuntu.com vivid-updates/restricted Sources [14 B] 12:46:49 O: Get:19 http://ports.ubuntu.com vivid-updates/universe Sources [14 B] 12:46:49 O: Get:20 http://ports.ubuntu.com vivid-updates/main armhf Packages [14 B] 12:46:49 O: Get:21 http://ports.ubuntu.com vivid-updates/restricted armhf Packages [14 B] 12:46:49 O: Get:22 http://ports.ubuntu.com vivid-updates/universe armhf Packages [14 B] 12:46:49 O: Get:23 http://ports.ubuntu.com vivid-updates/main Translation-en [14 B] 12:46:49 O: Get:24 http://ports.ubuntu.com vivid-updates/restricted Translation-en [14 B] 12:46:50 O: Get:25 http://ports.ubuntu.com vivid-updates/universe Translation-en [14 B] 12:46:50 O: Get:26 http://ports.ubuntu.com vivid-security/main Sources [14 B] 12:46:50 O: Get:27 http://ports.ubuntu.com vivid-security/restricted Sources [14 B] 12:46:50 O: Get:28 http://ports.ubuntu.com vivid-security/universe Sources [14 B] 12:46:50 O: Get:29 http://ports.ubuntu.com vivid-security/main armhf Packages [14 B] 12:46:50 O: Get:30 http://ports.ubuntu.com vivid-security/restricted armhf Packages [14 B] 12:46:50 O: Get:31 http://ports.ubuntu.com vivid-security/universe armhf Packages [14 B] 12:46:50 O: Get:32 http://ports.ubuntu.com vivid-security/main Translation-en [14 B] 12:46:50 O: Get:33 http://ports.ubuntu.com vivid-security/restricted Translation-en [14 B] 12:46:50 O: Get:34 http://ports.ubuntu.com vivid-security/universe Translation-en [14 B] 12:46:50 O: Get:35 http://ports.ubuntu.com vivid-proposed/main Sources [24.4 kB] 12:46:50 O: Get:36 http://ports.ubuntu.com vivid-proposed/restricted Sources [28 B] 12:46:51 O: Get:37 http://ports.ubuntu.com vivid-proposed/universe Sources [107 kB] 12:46:51 O: Get:38 http://ports.ubuntu.com vivid-proposed/main armhf Packages [37.8 kB] 12:46:51 O: Get:39 http://ports.ubuntu.com vivid-proposed/restricted armhf Packages [28 B] 12:46:51 O: Get:40 http://ports.ubuntu.com vivid-proposed/universe armhf Packages [103 kB] 12:46:51 O: Get:41 http://ports.ubuntu.com vivid-proposed/main Translation-en [17.2 kB] 12:46:51 O: Get:42 http://ports.ubuntu.com vivid-proposed/restricted Translation-en [28 B] 12:46:51 O: Get:43 http://ports.ubuntu.com vivid-proposed/universe Translation-en [69.0 kB] 12:47:23 O: Fetched 21.8 MB in 47s (460 kB/s) 12:47:39 O: Reading package lists... 12:47:39 E: adt-run: DBG: testbed command exited with code 0 12:47:39 E: adt-run: DBG: testbed command ['bash', '-ec', '[ ! -e /run/autopkgtest_no_reboot.stamp ] || exit 0;for d in /boot /etc/init /etc/init.d /etc/systemd/system /lib/systemd/system; do s=/tmp/adt-run.d3o9Xe/${d//\\//_}.stamp; [ ! -d $d ] || [ `stat -c %Y $d` = `stat -c %Y $s` ]; done'], kind short, sout raw, serr raw, env ['LANG=C.UTF-8'] 12:47:39 E: adt-run: DBG: testbed command exited with code 0 12:47:39 E: adt-run: DBG: testbed command ['dpkg', '--print-architecture'], kind short, sout pipe, serr pipe, env ['LANG=C.UTF-8'] 12:47:40 E: adt-run: DBG: testbed command exited with code 0 12:47:40 E: adt-run [12:47:40]: testbed dpkg architecture: armhf 12:47:40 E: adt-run: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/adt-run.d3o9Xe/testbed-packages"], kind short, sout raw, serr pipe, env ['LANG=C.UTF-8'] 12:47:40 E: adt-run: DBG: testbed command exited with code 0 12:47:40 E: adt-run: DBG: sending command to testbed: copyup /tmp/adt-run.d3o9Xe/testbed-packages pkgsrc/testbed-packages 12:47:41 E: adt-run: DBG: got reply from testbed: ok 12:47:41 E: adt-run: DBG: testbed supports reboot, creating /tmp/autopkgtest-reboot 12:47:41 E: adt-run: DBG: testbed command ['sh', '-ecC', '[ ! -e /tmp/autopkgtest-reboot ] || exit 0; /bin/echo -e \'#!/bin/sh -e\\n[ -n "$1" ] || { echo "Usage: $0 " >&2; exit 1; }\\necho "$1" > /run/autopkgtest-reboot-mark\\nkill -KILL $PPID\\n\' > /tmp/autopkgtest-reboot;chmod 755 /tmp/autopkgtest-reboot;[ -L /sbin/autopkgtest-reboot ] || ln -s /tmp/autopkgtest-reboot /sbin/autopkgtest-reboot'], kind short, sout raw, serr raw, env ['LANG=C.UTF-8'] 12:47:41 E: adt-run: DBG: testbed command exited with code 0 12:47:41 E: adt-run: DBG: Binaries: initialising 12:47:41 E: adt-run: DBG: Binaries: reset 12:47:41 E: adt-run: DBG: / binaries rmtree pkgsrc/binaries 12:47:41 E: adt-run [12:47:41]: @@@@@@@@@@@@@@@@@@@@ unbuilt-tree /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../tests/getpkgsrc 12:47:41 E: adt-run: DBG: blame += /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../tests/getpkgsrc 12:47:41 E: adt-run: DBG: testbed reset: modified=False, deps_installed=[](r: False), deps_new=[](r: False) 12:47:41 E: adt-run: DBG: testbed command ['mkdir', '-p', '/tmp/adt-run.d3o9Xe'], kind short, sout raw, serr pipe, env ['LANG=C.UTF-8'] 12:47:41 E: adt-run: DBG: testbed command exited with code 0 12:47:41 E: adt-run: DBG: sending command to testbed: copydown /var/lib/jenkins/slaves/krillin-09/workspace/vivid-boottest-unity-scopes-api/test-runner/scripts/../tests/getpkgsrc/ /tmp/adt-run.d3o9Xe/ubtree-getpkgsrc/ 12:47:42 E: adt-run: DBG: got reply from testbed: ok 12:47:42 E: adt-run: DBG: testbed command ['chown', '-R', 'phablet', '--', '/tmp/adt-run.d3o9Xe/ubtree-getpkgsrc'], kind short, sout raw, serr pipe, env ['LANG=C.UTF-8'] 12:47:42 E: adt-run: DBG: testbed command exited with code 0 12:47:42 E: adt-run: DBG: testbed command ['sh', '-ec', 'su --shell=/bin/sh phablet -c \'set -e; exec 3>&1 >&2; set -x; cd .; builddir=$(mktemp -d /tmp/adt-run.d3o9Xe/build.XXX); cd $builddir; cp -rd --preserve=timestamps -- "/tmp/adt-run.d3o9Xe/ubtree-getpkgsrc" real-tree; chmod -R a+rX .; cd [a-z0-9]*/.; pwd >&3; sed -n "1 {s/).*//; s/ (/\\n/; p}" debian/changelog >&3; set +e; grep -q "^Restrictions:.*\\bbuild-needed\\b" debian/tests/control 2>/dev/null; echo $? >&3\''], kind build, sout pipe, serr raw, env ['LANG=C.UTF-8'] 12:47:42 E: + cd . 12:47:42 E: + mktemp -d /tmp/adt-run.d3o9Xe/build.XXX 12:47:42 E: + builddir=/tmp/adt-run.d3o9Xe/build.JDt 12:47:42 E: + cd /tmp/adt-run.d3o9Xe/build.JDt 12:47:42 E: + cp -rd --preserve=timestamps -- /tmp/adt-run.d3o9Xe/ubtree-getpkgsrc real-tree 12:47:42 E: + chmod -R a+rX . 12:47:42 E: + cd real-tree/. 12:47:42 E: + pwd 12:47:42 E: + sed -n 1 {s/).*//; s/ (/\n/; p} debian/changelog 12:47:42 E: + set +e 12:47:42 E: + grep -q ^Restrictions:.*\bbuild-needed\b debian/tests/control 12:47:42 E: + echo 1 12:47:42 E: adt-run: DBG: testbed command exited with code 0 12:47:42 E: adt-run [12:47:42]: testing package getpkgsrc version 0.1 12:47:42 E: adt-run [12:47:42]: build not needed 12:47:42 E: adt-run: DBG: sending command to testbed: copyup /tmp/adt-run.d3o9Xe/build.JDt/real-tree/ pkgsrc/tests-tree/ 12:47:43 E: adt-run: DBG: got reply from testbed: ok 12:47:43 E: adt-run: DBG: processing dependency dpkg-dev 12:47:43 E: adt-run: DBG: Test defined: name getpkgsrc path debian/tests/getpkgsrc command "None" restrictions [] features [] depends ['dpkg-dev'] clicks [] installed clicks [] 12:47:43 E: adt-run [12:47:43]: test getpkgsrc: preparing 12:47:43 E: adt-run: DBG: testbed reset: modified=False, deps_installed=[](r: False), deps_new=['dpkg-dev'](r: False) 12:47:43 E: adt-run: DBG: install_deps: deps_new=['dpkg-dev'], recommends=False 12:47:43 E: adt-run: DBG: Binaries: publish 12:47:43 E: adt-run: DBG: Binaries: no registered binaries, not publishing anything 12:47:43 E: adt-run: DBG: install-deps: satisfying dpkg-dev 12:47:43 E: adt-run: DBG: install-deps: architecture resolved: dpkg-dev 12:47:43 E: adt-run: DBG: testbed command ['test', '-w', '/var/lib/dpkg/status'], kind short, sout raw, serr raw, env ['LANG=C.UTF-8'] 12:47:43 E: adt-run: DBG: testbed command exited with code 0 12:47:43 E: adt-run: DBG: can use apt-get on testbed: True 12:47:43 E: adt-run: DBG: testbed command ['mkdir', '-p', '/tmp/adt-run.d3o9Xe'], kind short, sout raw, serr pipe, env ['LANG=C.UTF-8'] 12:47:44 E: adt-run: DBG: testbed command exited with code 0 12:47:44 E: adt-run: DBG: sending command to testbed: copydown pkgsrc/adt-satdep.deb /tmp/adt-run.d3o9Xe/adt-satdep.deb 12:47:44 E: adt-run: DBG: got reply from testbed: ok 12:47:44 E: adt-run: DBG: testbed command ['chown', '-R', 'phablet', '--', '/tmp/adt-run.d3o9Xe/adt-satdep.deb'], kind short, sout raw, serr pipe, env ['LANG=C.UTF-8'] 12:47:44 E: adt-run: DBG: testbed command exited with code 0 12:47:44 E: adt-run: DBG: testbed command ['dpkg', '--unpack', '/tmp/adt-run.d3o9Xe/adt-satdep.deb'], kind short, sout pipe, serr pipe, env ['LANG=C.UTF-8'] 12:47:45 E: adt-run: DBG: testbed command exited with code 0 12:47:45 E: adt-run: DBG: testbed command ['apt-get', 'install', '--quiet', '--quiet', '--assume-yes', '--fix-broken', '-o', 'APT::Install-Recommends=False', '-o', 'Debug::pkgProblemResolver=true'], kind install, sout raw, serr raw, env ['DEBIAN_FRONTEND=noninteractive', 'APT_LISTBUGS_FRONTEND=none', 'LANG=C.UTF-8'] 12:47:48 E: Starting pkgProblemResolver with broken count: 0 12:47:49 E: Starting 2 pkgProblemResolver with broken count: 0 12:47:49 E: Done 12:47:49 E: Starting pkgProblemResolver with broken count: 0 12:47:49 E: Starting 2 pkgProblemResolver with broken count: 0 12:47:49 E: Done 12:47:55 O: Selecting previously unselected package perl-modules. 12:47:55 O: (Reading database ... 40767 files and directories currently installed.) 12:47:55 O: Preparing to unpack .../perl-modules_5.20.1-5_all.deb ... 12:47:55 O: Unpacking perl-modules (5.20.1-5) ... 12:48:00 O: Selecting previously unselected package libgdbm3:armhf. 12:48:01 O: Preparing to unpack .../libgdbm3_1.8.3-13.1_armhf.deb ... 12:48:01 O: Unpacking libgdbm3:armhf (1.8.3-13.1) ... 12:48:01 O: Selecting previously unselected package perl. 12:48:01 O: Preparing to unpack .../perl_5.20.1-5_armhf.deb ... 12:48:01 O: Unpacking perl (5.20.1-5) ... 12:48:06 O: Selecting previously unselected package libtimedate-perl. 12:48:06 O: Preparing to unpack .../libtimedate-perl_2.3000-2_all.deb ... 12:48:06 O: Unpacking libtimedate-perl (2.3000-2) ... 12:48:06 O: Selecting previously unselected package libdpkg-perl. 12:48:06 O: Preparing to unpack .../libdpkg-perl_1.17.23ubuntu1_all.deb ... 12:48:06 O: Unpacking libdpkg-perl (1.17.23ubuntu1) ... 12:48:06 O: Selecting previously unselected package patch. 12:48:06 O: Preparing to unpack .../patch_2.7.4-1_armhf.deb ... 12:48:06 O: Unpacking patch (2.7.4-1) ... 12:48:06 O: Selecting previously unselected package make. 12:48:06 O: Preparing to unpack .../make_4.0-8.1_armhf.deb ... 12:48:06 O: Unpacking make (4.0-8.1) ... 12:48:07 O: Selecting previously unselected package binutils. 12:48:07 O: Preparing to unpack .../binutils_2.25-2ubuntu2_armhf.deb ... 12:48:07 O: Unpacking binutils (2.25-2ubuntu2) ... 12:48:08 O: Selecting previously unselected package dpkg-dev. 12:48:08 O: Preparing to unpack .../dpkg-dev_1.17.23ubuntu1_all.deb ... 12:48:08 O: Unpacking dpkg-dev (1.17.23ubuntu1) ... 12:48:10 O: Setting up libgdbm3:armhf (1.8.3-13.1) ... 12:48:10 O: Setting up patch (2.7.4-1) ... 12:48:10 O: Setting up make (4.0-8.1) ... 12:48:10 O: Setting up binutils (2.25-2ubuntu2) ... 12:48:10 O: Setting up perl-modules (5.20.1-5) ... 12:48:10 O: Setting up perl (5.20.1-5) ... 12:48:10 O: update-alternatives: using /usr/bin/prename to provide /usr/bin/rename (rename) in auto mode 12:48:10 O: Setting up libtimedate-perl (2.3000-2) ... 12:48:10 O: Setting up libdpkg-perl (1.17.23ubuntu1) ... 12:48:10 O: Setting up dpkg-dev (1.17.23ubuntu1) ... 12:48:10 O: Setting up adt-satdep (0) ... 12:48:10 O: Processing triggers for libc-bin (2.19-13ubuntu3) ... 12:48:13 E: adt-run: DBG: testbed command exited with code 0 12:48:13 E: adt-run: DBG: testbed command ['dpkg', '--status', 'adt-satdep'], kind short, sout pipe, serr pipe, env ['LANG=C.UTF-8'] 12:48:13 E: adt-run: DBG: testbed command exited with code 0 12:48:13 E: adt-run: DBG: testbed command ['dpkg', '--purge', 'adt-satdep'], kind short, sout raw, serr raw, env ['LANG=C.UTF-8'] 12:48:14 O: (Reading database ... 42531 files and directories currently installed.) 12:48:14 O: Removing adt-satdep (0) ... 12:48:14 E: adt-run: DBG: testbed command exited with code 0 12:48:14 E: adt-run: DBG: testbed command ['sh', '-ec', "dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/adt-run.d3o9Xe/getpkgsrc-packages.all"], kind short, sout raw, serr pipe, env ['LANG=C.UTF-8'] 12:48:14 E: adt-run: DBG: testbed command exited with code 0 12:48:14 E: adt-run: DBG: sending command to testbed: copyup /tmp/adt-run.d3o9Xe/getpkgsrc-packages.all pkgsrc/getpkgsrc-packages.all 12:48:15 E: adt-run: DBG: got reply from testbed: ok 12:48:15 E: adt-run: DBG: testbed command ['test', '-e', '/tmp/adt-run.d3o9Xe/build.JDt/real-tree'], kind short, sout raw, serr raw, env ['LANG=C.UTF-8'] 12:48:15 E: adt-run: DBG: testbed command exited with code 0 12:48:15 E: adt-run: DBG: copydown: tb path /tmp/adt-run.d3o9Xe/build.JDt/real-tree already exists 12:48:15 E: adt-run [12:48:15]: test getpkgsrc: [----------------------- 12:48:15 E: adt-run: DBG: testbed command ['su', '-s', '/bin/bash', 'phablet', '-c', 'set -e; export USER=`id -nu`; . /etc/profile >/dev/null 2>&1 || true; . ~/.profile >/dev/null 2>&1 || true; buildtree="/tmp/adt-run.d3o9Xe/build.JDt/real-tree"; mkdir -p -m 1777 -- "/tmp/adt-run.d3o9Xe/getpkgsrc-artifacts"; export ADT_ARTIFACTS="/tmp/adt-run.d3o9Xe/getpkgsrc-artifacts"; export ADTTMP=$(mktemp -d --tmpdir adttmp.XXXXXX); trap "rm -rf $ADTTMP" EXIT INT QUIT PIPE; chmod 755 $ADTTMP; cd "$buildtree"; export LANG=C.UTF-8; chmod +x /tmp/adt-run.d3o9Xe/build.JDt/real-tree/debian/tests/getpkgsrc; touch /tmp/adt-run.d3o9Xe/getpkgsrc-stdout /tmp/adt-run.d3o9Xe/getpkgsrc-stderr; /tmp/adt-run.d3o9Xe/build.JDt/real-tree/debian/tests/getpkgsrc 2> >(tee -a /tmp/adt-run.d3o9Xe/getpkgsrc-stderr >&2) > >(tee -a /tmp/adt-run.d3o9Xe/getpkgsrc-stdout); '], kind test, sout raw, serr raw, env ['LANG=C.UTF-8'] 12:48:16 O: Reading package lists... 12:48:16 O: Building dependency tree... 12:48:16 O: Reading state information... 12:48:17 O: NOTICE: 'unity-scopes-api' packaging is maintained in the 'Bzr' version control system at: 12:48:17 O: lp:unity-scopes-api 12:48:17 O: Please use: 12:48:17 O: bzr branch lp:unity-scopes-api 12:48:17 O: to retrieve the latest (possibly unreleased) updates to the package. 12:48:17 O: Need to get 636 kB of source archives. 12:48:17 O: Get:1 http://ports.ubuntu.com/ubuntu-ports/ vivid-proposed/universe unity-scopes-api 0.6.13+15.04.20150211-0ubuntu1 (dsc) [2964 B] 12:48:17 O: Get:2 http://ports.ubuntu.com/ubuntu-ports/ vivid-proposed/universe unity-scopes-api 0.6.13+15.04.20150211-0ubuntu1 (tar) [605 kB] 12:48:18 O: Get:3 http://ports.ubuntu.com/ubuntu-ports/ vivid-proposed/universe unity-scopes-api 0.6.13+15.04.20150211-0ubuntu1 (diff) [27.2 kB] 12:48:19 O: dpkg-source: info: extracting unity-scopes-api in unity-scopes-api-0.6.13+15.04.20150211 12:48:19 O: dpkg-source: info: unpacking unity-scopes-api_0.6.13+15.04.20150211.orig.tar.gz 12:48:19 O: dpkg-source: info: applying unity-scopes-api_0.6.13+15.04.20150211-0ubuntu1.diff.gz 12:48:19 O: Fetched 636 kB in 1s (523 kB/s) 12:48:19 E: adt-run: DBG: testbed command exited with code 0 12:48:20 E: adt-run [12:48:20]: test getpkgsrc: -----------------------] 12:48:20 E: adt-run: DBG: testbed executing test finished with exit status 0 12:48:20 E: adt-run: DBG: sending command to testbed: copyup /tmp/adt-run.d3o9Xe/getpkgsrc-stdout pkgsrc/getpkgsrc-stdout 12:48:20 E: adt-run: DBG: got reply from testbed: ok 12:48:20 E: adt-run: DBG: sending command to testbed: copyup /tmp/adt-run.d3o9Xe/getpkgsrc-stderr pkgsrc/getpkgsrc-stderr 12:48:21 E: adt-run: DBG: got reply from testbed: ok 12:48:21 E: adt-run [12:48:21]: test getpkgsrc: - - - - - - - - - - results - - - - - - - - - - 12:48:21 O: getpkgsrc PASS 12:48:21 E: adt-run: DBG: sending command to testbed: copyup /tmp/adt-run.d3o9Xe/getpkgsrc-artifacts/ pkgsrc/artifacts/ 12:48:23 E: tar: Skipping to next header 12:48:23 E: tar: Exiting with failure status due to previous errors 12:48:24 E: Exit request sent. 12:48:24 E: Unexpected cleanup error: 12:48:24 E: Traceback (most recent call last): 12:48:24 E: File "/usr/share/autopkgtest/python/VirtSubproc.py", line 676, in mainloop 12:48:24 E: command() 12:48:24 E: File "/usr/share/autopkgtest/python/VirtSubproc.py", line 614, in command 12:48:24 E: r = f(c, ce) 12:48:24 E: File "/usr/share/autopkgtest/python/VirtSubproc.py", line 560, in cmd_copyup 12:48:24 E: copyupdown(c, ce, True) 12:48:24 E: File "/usr/share/autopkgtest/python/VirtSubproc.py", line 447, in copyupdown 12:48:24 E: copyupdown_internal(ce[0], c[1:], upp) 12:48:24 E: File "/usr/share/autopkgtest/python/VirtSubproc.py", line 546, in copyupdown_internal 12:48:24 E: (wh, ['source', 'destination'][sdn], status)) 12:48:24 E: File "/usr/share/autopkgtest/python/VirtSubproc.py", line 84, in bomb 12:48:24 E: raise Quit(12, progname + ": failure: %s" % m) 12:48:24 E: VirtSubproc.Quit: (12, ': failure: copyup destination failed, status 2') 12:48:24 E: 12:48:24 E: During handling of the above exception, another exception occurred: 12:48:24 E: 12:48:24 E: Traceback (most recent call last): 12:48:24 E: File "/usr/share/autopkgtest/python/VirtSubproc.py", line 648, in error_cleanup 12:48:24 E: cleanup() 12:48:24 E: File "/usr/share/autopkgtest/python/VirtSubproc.py", line 639, in cleanup 12:48:24 E: caller.hook_cleanup() 12:48:24 E: File "/usr/bin/adt-virt-ssh", line 418, in hook_cleanup 12:48:24 E: shutil.rmtree(workdir) 12:48:24 E: File "/usr/lib/python3.4/shutil.py", line 463, in rmtree 12:48:24 E: _rmtree_safe_fd(fd, path, onerror) 12:48:24 E: File "/usr/lib/python3.4/shutil.py", line 421, in _rmtree_safe_fd 12:48:24 E: onerror(os.unlink, fullname, sys.exc_info()) 12:48:24 E: File "/usr/lib/python3.4/shutil.py", line 419, in _rmtree_safe_fd 12:48:24 E: os.unlink(name, dir_fd=topfd) 12:48:24 E: FileNotFoundError: [Errno 2] No such file or directory: 'ssh_control-phablet@localhost:2222' 12:48:24 E: 12:48:24 E: while cleaning up because of another error: 12:48:24 E: : failure: copyup destination failed, status 2 12:48:24 E: adt-run: DBG: bomb unexpected eof from the testbed 12:48:24 E: adt-run: DBG: testbed stop 12:48:24 E: adt-run: DBG: testbed close, scratch=/tmp/adt-run.d3o9Xe 12:48:24 E: adt-run: DBG: sending command to testbed: close 12:48:24 E: adt-run: DBG: bomb cannot send to testbed: ['BrokenPipeError: [Errno 32] Broken pipe\n'] 12:48:24 E: adt-run: DBG: testbed stop 12:48:24 E: adt-run [12:48:24]: ERROR: testbed failed: cannot send to testbed: ['BrokenPipeError: [Errno 32] Broken pipe\n'] 12:48:24 E: adt-run: DBG: testbed stop 12:48:24 E: adt-run: DBG: / tests-tree rmtree pkgsrc/tests-tree