maas-builds team mailing list archive

[Jenkins Notification <devnull@xxxxxxxxxxxxx>]

Built with lp:maas (revno: 2649) and lp:~maas-maintainers/maas/packaging (revno: 290)

See http://d-jenkins.ubuntu-ci:8080/job/utopic-adt-maas/322/

[...truncated 6169 lines...]
squid-deb-proxy stop/waiting
squid-deb-proxy start/running, process 10428
maas-txlongpoll start/running, process 10509
maas-region-celery start/running, process 10594
Setting up libnss3-nssdb (2:3.16.3-1ubuntu1) ...
Setting up libnss3:amd64 (2:3.16.3-1ubuntu1) ...
Setting up librados2 (0.80.5-1) ...
Setting up librbd1 (0.80.5-1) ...
Setting up tgt (1:1.0.43-0ubuntu4) ...
tgt start/running, process 10656
Processing triggers for ureadahead (0.100.0-16) ...
Setting up maas-cluster-controller (1.6+bzr2367+2649+290-0ubuntu4) ...
 * Restarting web server apache2
(98)Address already in use: AH00072: make_sock: could not bind to address [::]:80
(98)Address already in use: AH00072: make_sock: could not bind to address 0.0.0.0:80
no listening sockets available, shutting down
AH00015: Unable to open logs
Action 'start' failed.
The Apache error log may have more information.
   ...fail!
 * The apache2 instance did not start within 20 seconds. Please read the log files to discover problems
invoke-rc.d: initscript apache2, action "restart" failed.
maas-pserv start/running, process 10861
maas-cluster-celery start/running, process 10895
Processing triggers for ureadahead (0.100.0-16) ...
Setting up maas (1.6+bzr2367+2649+290-0ubuntu4) ...
Setting up adt-satdep (0) ...
Processing triggers for libc-bin (2.19-4ubuntu1) ...
adt-run1: testbed command exited with code 0
adt-run1: testbed command ['dpkg', '-s', 'adt-satdep'], kind short, sout pipe, serr pipe, env ['LANG=C']
adt-run1: testbed command exited with code 0
adt-run1: testbed command ['sh', '-ec', u"dpkg-query --show -f '${Package}\\t${Version}\\n' > /tmp/adt-run.756aj1/ubtree0t-maas-package-test-packages.all"], kind short, sout raw, serr pipe, env ['LANG=C']
adt-run1: testbed command exited with code 0
adt-run2: >> copyup /tmp/adt-run.756aj1/ubtree0t-maas-package-test-packages.all /home/ubuntu/jenkins-jobs/workspace/utopic-adt-maas/results/ubtree0t-maas-package-test-packages.all
adt-run2: << ok
adt-run1: testbed command ['mkdir', '-p', '/tmp/adt-run.IRRYBF/ubtree0-build'], kind short, sout raw, serr pipe, env ['LANG=C']
adt-run1: testbed command exited with code 0
adt-run2: >> copydown /home/ubuntu/jenkins-jobs/workspace/utopic-adt-maas/results/ubtree0-tests-tree/ /tmp/adt-run.IRRYBF/ubtree0-build/real-tree/
adt-run2: << ok
adt-run1: testbed command ['chown', '-R', 'ubuntu', '--', '/tmp/adt-run.IRRYBF/ubtree0-build/real-tree'], kind short, sout raw, serr pipe, env ['LANG=C']
adt-run1: testbed command exited with code 0
adt-run2: >> quote-shstring set%20-e%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20buildtree%3D%22/tmp/adt-run.IRRYBF/ubtree0-build/real-tree%22%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20rm%20-rf%20%22/tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts%22%3B%20mkdir%20-m%201777%20--%20%22/tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts%22%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20export%20ADT_ARTIFACTS%3D%22/tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts%22%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20export%20ADTTMP%3D%24%28mktemp%20-d%20--tmpdir%20adttmp.XXXXXX%29%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20trap%20%22rm%20-rf%20%24ADTTMP%22%20EXIT%20INT%20QUIT%20PIPE%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20chmod%20755%20%24ADTTMP%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20cd%20%22%24buildtree%22%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20chmod%20%2Bx%20/tmp/adt-run.IRRYBF/ubtree0-build/real-tree/debian/tests/maas-package-test%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20touch%20/tmp/adt-run.756aj1/ubtree0t-maas-package-test-stdout%20/tmp/adt-run.756aj1/ubtree0t-maas-package-test-stderr%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20/tmp/adt-run.IRRYBF/ubtree0-build/real-tree/debian/tests/maas-package-test%202%3E%20%3E%28tee%20/tmp/adt-run.756aj1/ubtree0t-maas-package-test-stderr%20%3E%262%29%20%3E%20%3E%28tee%20/tmp/adt-run.756aj1/ubtree0t-maas-package-test-stdout%29%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20
adt-run2: << ok set%20-e%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20buildtree%3D%22/tmp/adt-run.IRRYBF/ubtree0-build/real-tree%22%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20rm%20-rf%20%22/tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts%22%3B%20mkdir%20-m%201777%20--%20%22/tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts%22%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20export%20ADT_ARTIFACTS%3D%22/tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts%22%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20export%20ADTTMP%3D%24%28mktemp%20-d%20--tmpdir%20adttmp.XXXXXX%29%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20trap%20%22rm%20-rf%20%24ADTTMP%22%20EXIT%20INT%20QUIT%20PIPE%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20chmod%20755%20%24ADTTMP%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20cd%20%22%24buildtree%22%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20chmod%20%2Bx%20/tmp/adt-run.IRRYBF/ubtree0-build/real-tree/debian/tests/maas-package-test%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20touch%20/tmp/adt-run.756aj1/ubtree0t-maas-package-test-stdout%20/tmp/adt-run.756aj1/ubtree0t-maas-package-test-stderr%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20/tmp/adt-run.IRRYBF/ubtree0-build/real-tree/debian/tests/maas-package-test%202%3E%20%3E%28tee%20/tmp/adt-run.756aj1/ubtree0t-maas-package-test-stderr%20%3E%262%29%20%3E%20%3E%28tee%20/tmp/adt-run.756aj1/ubtree0t-maas-package-test-stdout%29%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20%20
adt-run [2014-08-04 12:12:03]: & ubtree0t-maas-package-test: [-----------------------
adt-run1: testbed command ['bash', '-c', 'set -e\n                    buildtree="/tmp/adt-run.IRRYBF/ubtree0-build/real-tree"\n                    rm -rf "/tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts"; mkdir -m 1777 -- "/tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts"\n                    export ADT_ARTIFACTS="/tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts"\n                    export ADTTMP=$(mktemp -d --tmpdir adttmp.XXXXXX)\n                    trap "rm -rf $ADTTMP" EXIT INT QUIT PIPE\n                    chmod 755 $ADTTMP\n                    cd "$buildtree"\n                    chmod +x /tmp/adt-run.IRRYBF/ubtree0-build/real-tree/debian/tests/maas-package-test\n                     touch /tmp/adt-run.756aj1/ubtree0t-maas-package-test-stdout /tmp/adt-run.756aj1/ubtree0t-maas-package-test-stderr\n                     /tmp/adt-run.IRRYBF/ubtree0-build/real-tree/debian/tests/maas-package-test 2> >(tee /tmp/adt-run.756aj1/ubtree0t-maas-package-test-stderr >&2) > >(tee /tmp/adt-run.756aj1/ubtree0t-maas-package-test-stdout)\n                  '], kind test, sout raw, serr raw, env ['LANG=C']
Ignoring indexes: https://pypi.python.org/simple/
Downloading/unpacking nose-timer
  Running setup.py (path:/tmp/pip_build_root/nose-timer/setup.py) egg_info for package nose-timer
    
Installing collected packages: nose-timer
  Running setup.py install for nose-timer
    
Successfully installed nose-timer
Cleaning up...
maas-integration.TestMAASIntegration.test_create_admin ... ok
maas-integration.TestMAASIntegration.test_restart_dbus_avahi ... SKIP: Avahi/DBUS are not used anymore
maas-integration.TestMAASIntegration.test_update_maas_url ... ok
maas-integration.TestMAASIntegration.test_restart_provisioning_server ... ok
maas-integration.TestMAASIntegration.test_check_initial_services ... ok
maas-integration.TestMAASIntegration.test_update_preseed_arm ... ok
maas-integration.TestMAASIntegration.test_login_api ... ok
maas-integration.TestMAASIntegration.test_set_http_proxy ... ok
maas-integration.TestMAASIntegration.test_cluster_connected ... ok
maas-integration.TestMAASIntegration.test_set_up_dhcp_region ... ok
maas-integration.TestMAASIntegration.test_check_dhcp_service ... ok
maas-integration.TestMAASIntegration.test_set_up_dhcp_cluster ... SKIP: Not testing cluster controller
maas-integration.TestMAASIntegration.test_update_dns_config ... ok
maas-integration.TestMAASIntegration.test_add_new_zones ... ok
maas-integration.TestMAASIntegration.test_list_zones ... ok
maas-integration.TestMAASIntegration.test_delete_zone ... ok
maas-integration.TestMAASIntegration.test_import_boot_images ... ok
maas-integration.TestMAASIntegration.test_imported_boot_images ... ok
maas-integration.TestMAASIntegration.test_boot_nodes_enlist ... ok
maas-integration.TestMAASIntegration.test_check_nodes_declared ... ok
maas-integration.TestMAASIntegration.test_assign_nodes_to_test_zone ... ok
maas-integration.TestMAASIntegration.test_set_nodes_ipmi_config ... ok
maas-integration.TestMAASIntegration.test_commission_nodes ... ok
maas-integration.TestMAASIntegration.test_check_nodes_ready ... ok
maas-integration.TestMAASIntegration.test_apply_tag_to_all_nodes ... ok
maas-integration.TestMAASIntegration.test_check_tag_applied_to_all_nodes ... ok
maas-integration.TestMAASIntegration.test_configure_juju ... ok
maas-integration.TestMAASIntegration.test_juju_bootstrap ... ERROR
SKIP: Not testing Cluster controller

======================================================================
ERROR: maas-integration.TestMAASIntegration.test_juju_bootstrap
----------------------------------------------------------------------
_StringException: juju --debug bootstrap --upload-tools: {{{
2014-08-04 16:34:57 INFO juju.cmd supercommand.go:302 running juju-1.18.4-utopic-amd64 [gc]
2014-08-04 16:34:57 DEBUG juju.environs.configstore disk.go:64 Making /root/.juju/environments
2014-08-04 16:34:57 DEBUG juju.provider.maas environprovider.go:30 opening environment "maas".
2014-08-04 16:34:57 INFO juju.environs.bootstrap synctools.go:34 checking that upload is possible
2014-08-04 16:34:57 DEBUG juju.environs.simplestreams simplestreams.go:388 fetchData failed for "images/streams/v1/index.sjson": file 'e2b87803-c997-4b70-8a07-07c23f51c860-images/streams/v1/index.sjson' not found not found
2014-08-04 16:34:57 DEBUG juju.environs.simplestreams simplestreams.go:362 cannot load index "streams/v1/index.sjson": invalid URL "images/streams/v1/index.sjson" not found
2014-08-04 16:34:57 DEBUG juju.environs.simplestreams simplestreams.go:388 fetchData failed for "images/streams/v1/index.json": file 'e2b87803-c997-4b70-8a07-07c23f51c860-images/streams/v1/index.json' not found not found
2014-08-04 16:34:57 DEBUG juju.environs.simplestreams simplestreams.go:362 cannot load index "streams/v1/index.json": invalid URL "images/streams/v1/index.json" not found
2014-08-04 16:34:57 INFO juju.utils http.go:55 hostname SSL verification enabled
2014-08-04 16:34:58 INFO juju.utils http.go:55 hostname SSL verification enabled
2014-08-04 16:34:58 DEBUG juju.environs.simplestreams simplestreams.go:388 fetchData failed for "http://cloud-images.ubuntu.com/releases/streams/v1/mirrors.sjson": cannot find URL "http://cloud-images.ubuntu.com/releases/streams/v1/mirrors.sjson"; not found
2014-08-04 16:34:58 DEBUG juju.environs.simplestreams simplestreams.go:465 no mirror index file found
2014-08-04 16:34:58 DEBUG juju.environs.simplestreams simplestreams.go:366 read metadata index at "http://cloud-images.ubuntu.com/releases/streams/v1/index.sjson";
2014-08-04 16:34:58 DEBUG juju.environs.simplestreams simplestreams.go:533 candidate matches for products ["com.ubuntu.cloud:server:12.04:amd64" "com.ubuntu.cloud:server:12.04:i386" "com.ubuntu.cloud:server:12.04:armhf" "com.ubuntu.cloud:server:12.04:arm64" "com.ubuntu.cloud:server:12.04:ppc64" "com.ubuntu.cloud:server:12.10:amd64" "com.ubuntu.cloud:server:12.10:i386" "com.ubuntu.cloud:server:12.10:armhf" "com.ubuntu.cloud:server:12.10:arm64" "com.ubuntu.cloud:server:12.10:ppc64" "com.ubuntu.cloud:server:13.04:amd64" "com.ubuntu.cloud:server:13.04:i386" "com.ubuntu.cloud:server:13.04:armhf" "com.ubuntu.cloud:server:13.04:arm64" "com.ubuntu.cloud:server:13.04:ppc64" "com.ubuntu.cloud:server:13.10:amd64" "com.ubuntu.cloud:server:13.10:i386" "com.ubuntu.cloud:server:13.10:armhf" "com.ubuntu.cloud:server:13.10:arm64" "com.ubuntu.cloud:server:13.10:ppc64" "com.ubuntu.cloud:server:14.04:amd64" "com.ubuntu.cloud:server:14.04:i386" "com.ubuntu.cloud:server:14.04:armhf" "com.ubuntu.cloud:server:14.04:arm64" "com.ubuntu.cloud:server:14.04:ppc64" "com.ubuntu.cloud:server:14.10:amd64" "com.ubuntu.cloud:server:14.10:i386" "com.ubuntu.cloud:server:14.10:armhf" "com.ubuntu.cloud:server:14.10:arm64" "com.ubuntu.cloud:server:14.10:ppc64"] are [{Tue, 08 Jul 2014 15:49:14 +0000 products:1.0 image-ids joyent [{eu-ams-1 https://eu-ams-1.api.joyentcloud.com} {us-sw-1 https://us-sw-1.api.joyentcloud.com} {us-east-1 https://us-east-1.api.joyentcloud.com} {us-west-1 https://us-west-1.api.joyentcloud.com}] streams/v1/com.ubuntu.cloud:released:joyent.sjson [com.ubuntu.cloud:server:13.10:amd64 com.ubuntu.cloud:server:12.04:amd64 com.ubuntu.cloud:server:14.04:amd64]} {Mon, 04 Aug 2014 14:43:57 +0000 products:1.0 image-ids aws [{us-east-1 https://ec2.us-east-1.amazonaws.com} {ap-northeast-1 https://ec2.ap-northeast-1.amazonaws.com} {eu-west-1 https://ec2.eu-west-1.amazonaws.com} {ap-southeast-1 https://ec2.ap-southeast-1.amazonaws.com} {ap-southeast-2 https://ec2.ap-southeast-2.amazonaws.com} {us-west-2 https://ec2.us-west-2.amazonaws.com} {us-west-1 https://ec2.us-west-1.amazonaws.com} {sa-east-1 https://ec2.sa-east-1.amazonaws.com}] streams/v1/com.ubuntu.cloud:released:aws.sjson [com.ubuntu.cloud:server:13.10:amd64 com.ubuntu.cloud:server:13.04:amd64 com.ubuntu.cloud:server:12.10:i386 com.ubuntu.cloud:server:12.04:i386 com.ubuntu.cloud:server:14.04:i386 com.ubuntu.cloud:server:13.10:i386 com.ubuntu.cloud:server:10.04:amd64 com.ubuntu.cloud:server:10.04:i386 com.ubuntu.cloud:server:14.04:amd64 com.ubuntu.cloud:server:12.10:amd64 com.ubuntu.cloud:server:13.04:i386 com.ubuntu.cloud:server:11.10:amd64 com.ubuntu.cloud:server:12.04:amd64 com.ubuntu.cloud:server:11.10:i386 com.ubuntu.cloud:server:14.10:amd64 com.ubuntu.cloud:server:14.10:i386]} {Thu, 26 Jun 2014 14:51:58 +0000 products:1.0 image-ids hpcloud [{region-b.geo-1 https://region-b.geo-1.identity.hpcloudsvc.com:35357/v2.0/} {region-a.geo-1 https://region-a.geo-1.identity.hpcloudsvc.com:35357/v2.0/}] streams/v1/com.ubuntu.cloud:released:hpcloud.sjson [com.ubuntu.cloud:server:12.04:amd64 com.ubuntu.cloud:server:13.10:amd64 com.ubuntu.cloud:server:14.04:amd64 com.ubuntu.cloud:server:12.10:amd64]} {Sat, 26 Jul 2014 01:47:37 +0000 products:1.0 image-ids azure [{Japan West https://management.core.windows.net/} {West Europe https://management.core.windows.net/} {East US 2 https://management.core.windows.net/} {Southeast Asia https://management.core.windows.net/} {South Central US https://management.core.windows.net/} {Australia Southeast https://management.core.windows.net/} {Australia East https://management.core.windows.net/} {East Asia https://management.core.windows.net/} {East US https://management.core.windows.net/} {Central US https://management.core.windows.net/} {West US https://management.core.windows.net/} {North Europe https://management.core.windows.net/} {North Central US https://management.core.windows.net/} {Japan East https://management.core.windows.net/} {China East https://management.core.chinacloudapi.cn/} {China North https://management.core.chinacloudapi.cn/} {Brazil South https://management.core.windows.net/}] streams/v1/com.ubuntu.cloud:released:azure.sjson [com.ubuntu.cloud:server:12.04:amd64 com.ubuntu.cloud:server:13.10:amd64 com.ubuntu.cloud:server:14.04:amd64 com.ubuntu.cloud:server:12.10:amd64 com.ubuntu.cloud:server:14.10:amd64]} {Thu, 31 Jul 2014 16:12:40 +0000 products:1.0 image-ids aws-govcloud [{us-gov-west-1 https://ec2.us-gov-west-1.amazonaws-govcloud.com}] streams/v1/com.ubuntu.cloud:released:aws-govcloud.sjson [com.ubuntu.cloud:server:13.10:amd64 com.ubuntu.cloud:server:12.04:i386 com.ubuntu.cloud:server:14.04:i386 com.ubuntu.cloud:server:13.10:i386 com.ubuntu.cloud:server:10.04:amd64 com.ubuntu.cloud:server:14.04:amd64 com.ubuntu.cloud:server:10.04:i386 com.ubuntu.cloud:server:12.04:amd64]} {Sat, 26 Jul 2014 01:44:05 +0000 products:1.0 image-ids aws-cn [{cn-north-1 https://ec2.cn-north-1.amazonaws-cn.com.cn}] streams/v1/com.ubuntu.cloud:released:aws-cn.sjson [com.ubuntu.cloud:server:13.10:amd64 com.ubuntu.cloud:server:12.04:i386 com.ubuntu.cloud:server:14.04:i386 com.ubuntu.cloud:server:13.10:i386 com.ubuntu.cloud:server:14.04:amd64 com.ubuntu.cloud:server:12.04:amd64]}]
2014-08-04 16:34:58 DEBUG juju.environs.simplestreams simplestreams.go:845 finding products at path "streams/v1/com.ubuntu.cloud:released:joyent.sjson"
2014-08-04 16:34:58 INFO juju.utils http.go:55 hostname SSL verification enabled
2014-08-04 16:34:58 DEBUG juju.environs.simplestreams simplestreams.go:883 metadata: &{map[com.ubuntu.cloud:server:13.10:amd64:{saucy 13.10 amd64   map[20140227:0xc2100ee360 20140608:0xc2100ee420 20140519:0xc2100ee4e0 20140410:0xc2100ee5a0]} com.ubuntu.cloud:server:12.04:amd64:{precise 12.04 amd64   map[20140408:0xc2100ee840 20140227:0xc2100ee900 20140608:0xc2100ee9c0 20140519:0xc2100eea80]} com.ubuntu.cloud:server:14.04:amd64:{trusty 14.04 amd64   map[20140416:0xc2100eeba0 20140519:0xc2100eec60 20140608:0xc2100eed20]}] map[crsn:map[euww1:map[region:eu-ams-1 endpoint:https://eu-ams-1.api.joyentcloud.com] ussw1:map[region:us-sw-1 endpoint:https://us-sw-1.api.joyentcloud.com] usww1:map[region:us-west-1 endpoint:https://us-west-1.api.joyentcloud.com] usee1:map[region:us-east-1 endpoint:https://us-east-1.api.joyentcloud.com]]] Tue, 08 Jul 2014 15:49:14 +0000 products:1.0 com.ubuntu.cloud:released:joyent}
2014-08-04 16:34:58 INFO juju.cmd cmd.go:113 uploading tools for series [utopic precise trusty]
2014-08-04 16:34:58 DEBUG juju.environs.sync sync.go:277 Building tools
2014-08-04 16:34:58 DEBUG juju.environs.tools build.go:115 looking for: juju
2014-08-04 16:34:58 DEBUG juju.environs.tools build.go:156 checking: /usr/bin/jujud
2014-08-04 16:34:58 INFO juju.environs.tools build.go:162 found existing jujud
2014-08-04 16:34:58 INFO juju.environs.tools build.go:172 target: /tmp/juju-tools366629673/jujud
2014-08-04 16:34:58 DEBUG juju.environs.tools build.go:223 forcing version to 1.18.4.1
2014-08-04 16:34:58 DEBUG juju.environs.tools build.go:39 adding entry: &tar.Header{Name:"FORCE-VERSION", Mode:420, Uid:0, Gid:0, Size:8, ModTime:time.Time{sec:63542766898, nsec:0x2f324e5d, loc:(*time.Location)(0x18c2860)}, Typeflag:0x30, Linkname:"", Uname:"ubuntu", Gname:"ubuntu", Devmajor:0, Devminor:0, AccessTime:time.Time{sec:63542766898, nsec:0x2f324e5d, loc:(*time.Location)(0x18c2860)}, ChangeTime:time.Time{sec:63542766898, nsec:0x2f324e5d, loc:(*time.Location)(0x18c2860)}, Xattrs:map[string]string(nil)}
2014-08-04 16:34:58 DEBUG juju.environs.tools build.go:39 adding entry: &tar.Header{Name:"jujud", Mode:493, Uid:0, Gid:0, Size:36792784, ModTime:time.Time{sec:63542766898, nsec:0x2ef5455d, loc:(*time.Location)(0x18c2860)}, Typeflag:0x30, Linkname:"", Uname:"ubuntu", Gname:"ubuntu", Devmajor:0, Devminor:0, AccessTime:time.Time{sec:63542766898, nsec:0x2ef5455d, loc:(*time.Location)(0x18c2860)}, ChangeTime:time.Time{sec:63542766898, nsec:0x2ef5455d, loc:(*time.Location)(0x18c2860)}, Xattrs:map[string]string(nil)}
2014-08-04 16:35:03 INFO juju.environs.sync sync.go:296 built tools 1.18.4.1-utopic-amd64 (7186kB)
2014-08-04 16:35:03 DEBUG juju.environs.sync sync.go:201 Uploading tools for [utopic precise trusty]
2014-08-04 16:35:03 DEBUG juju.environs.sync sync.go:231 generating tarballs for [utopic precise trusty]
2014-08-04 16:35:03 DEBUG juju.environs.sync sync.go:251 generating tools metadata
2014-08-04 16:35:03 DEBUG juju.environs.tools tools.go:46 no architecture specified when finding tools, looking for any
2014-08-04 16:35:03 DEBUG juju.environs.tools tools.go:57 no series specified when finding tools, looking for any
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:388 fetchData failed for "file:///tmp/791701108/tools/streams/v1/index.sjson": stat /tmp/791701108/tools/streams/v1/index.sjson: no such file or directory
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:362 cannot load index "file:///tmp/791701108/tools/streams/v1/index.sjson": invalid URL "file:///tmp/791701108/tools/streams/v1/index.sjson" not found
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:388 fetchData failed for "file:///tmp/791701108/tools/streams/v1/index.json": stat /tmp/791701108/tools/streams/v1/index.json: no such file or directory
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:362 cannot load index "file:///tmp/791701108/tools/streams/v1/index.json": invalid URL "file:///tmp/791701108/tools/streams/v1/index.json" not found
2014-08-04 16:35:03 INFO juju.environs.tools simplestreams.go:378 Writing tools/streams/v1/index.json
2014-08-04 16:35:03 INFO juju.environs.tools simplestreams.go:378 Writing tools/streams/v1/com.ubuntu.juju:released:tools.json
2014-08-04 16:35:03 DEBUG juju.environs.sync sync.go:340 uploading tools to cloud storage
2014-08-04 16:35:03 INFO juju.environs.sync sync.go:142 using sync tools source: file:///tmp/791701108/tools
2014-08-04 16:35:03 INFO juju.environs.sync sync.go:65 listing available tools
2014-08-04 16:35:03 DEBUG juju.environs.tools tools.go:46 no architecture specified when finding tools, looking for any
2014-08-04 16:35:03 DEBUG juju.environs.tools tools.go:57 no series specified when finding tools, looking for any
2014-08-04 16:35:03 INFO juju.utils http.go:55 hostname SSL verification enabled
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:388 fetchData failed for "file:///tmp/791701108/tools/streams/v1/index.sjson": cannot find URL "file:///tmp/791701108/tools/streams/v1/index.sjson" not found
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:362 cannot load index "file:///tmp/791701108/tools/streams/v1/index.sjson": invalid URL "file:///tmp/791701108/tools/streams/v1/index.sjson" not found
2014-08-04 16:35:03 INFO juju.utils http.go:55 hostname SSL verification enabled
2014-08-04 16:35:03 INFO juju.utils http.go:55 hostname SSL verification enabled
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:388 fetchData failed for "file:///tmp/791701108/tools/streams/v1/mirrors.json": cannot find URL "file:///tmp/791701108/tools/streams/v1/mirrors.json" not found
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:465 no mirror index file found
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:446 no mirror information available for { }: mirror data for "com.ubuntu.juju:released:tools" not found
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:366 read metadata index at "file:///tmp/791701108/tools/streams/v1/index.json"
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:533 candidate matches for products ["com.ubuntu.juju:12.04:amd64" "com.ubuntu.juju:12.04:i386" "com.ubuntu.juju:12.04:armhf" "com.ubuntu.juju:12.04:arm64" "com.ubuntu.juju:12.04:ppc64" "com.ubuntu.juju:12.10:amd64" "com.ubuntu.juju:12.10:i386" "com.ubuntu.juju:12.10:armhf" "com.ubuntu.juju:12.10:arm64" "com.ubuntu.juju:12.10:ppc64" "com.ubuntu.juju:13.04:amd64" "com.ubuntu.juju:13.04:i386" "com.ubuntu.juju:13.04:armhf" "com.ubuntu.juju:13.04:arm64" "com.ubuntu.juju:13.04:ppc64" "com.ubuntu.juju:13.10:amd64" "com.ubuntu.juju:13.10:i386" "com.ubuntu.juju:13.10:armhf" "com.ubuntu.juju:13.10:arm64" "com.ubuntu.juju:13.10:ppc64" "com.ubuntu.juju:14.04:amd64" "com.ubuntu.juju:14.04:i386" "com.ubuntu.juju:14.04:armhf" "com.ubuntu.juju:14.04:arm64" "com.ubuntu.juju:14.04:ppc64" "com.ubuntu.juju:14.10:amd64" "com.ubuntu.juju:14.10:i386" "com.ubuntu.juju:14.10:armhf" "com.ubuntu.juju:14.10:arm64" "com.ubuntu.juju:14.10:ppc64"] are [{Mon, 04 Aug 2014 16:35:03 +0000 products:1.0 content-download  [] streams/v1/com.ubuntu.juju:released:tools.json [com.ubuntu.juju:12.04:amd64 com.ubuntu.juju:14.04:amd64 com.ubuntu.juju:14.10:amd64]}]
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:845 finding products at path "streams/v1/com.ubuntu.juju:released:tools.json"
2014-08-04 16:35:03 INFO juju.utils http.go:55 hostname SSL verification enabled
2014-08-04 16:35:03 DEBUG juju.environs.simplestreams simplestreams.go:883 metadata: &{map[com.ubuntu.juju:12.04:amd64:{ 1.18.4.1 amd64   map[20140804:0xc2100d61e0]} com.ubuntu.juju:14.04:amd64:{ 1.18.4.1 amd64   map[20140804:0xc2100d6540]} com.ubuntu.juju:14.10:amd64:{ 1.18.4.1 amd64   map[20140804:0xc2100d6720]}] map[] Mon, 04 Aug 2014 16:35:03 +0000 products:1.0 com.ubuntu.juju:released:tools}
2014-08-04 16:35:03 INFO juju.environs.sync sync.go:86 found 3 tools
2014-08-04 16:35:03 DEBUG juju.environs.sync sync.go:93 found source tool: &{1.18.4.1-precise-amd64 file:///tmp/791701108/tools/releases/juju-1.18.4.1-precise-amd64.tgz 38067ab24968b562927ee03b20af02ba0d93ef2ba404ddfd7e608bafb6308e92 7358691}
2014-08-04 16:35:03 DEBUG juju.environs.sync sync.go:93 found source tool: &{1.18.4.1-trusty-amd64 file:///tmp/791701108/tools/releases/juju-1.18.4.1-trusty-amd64.tgz 38067ab24968b562927ee03b20af02ba0d93ef2ba404ddfd7e608bafb6308e92 7358691}
2014-08-04 16:35:03 DEBUG juju.environs.sync sync.go:93 found source tool: &{1.18.4.1-utopic-amd64 file:///tmp/791701108/tools/releases/juju-1.18.4.1-utopic-amd64.tgz 38067ab24968b562927ee03b20af02ba0d93ef2ba404ddfd7e608bafb6308e92 7358691}
2014-08-04 16:35:03 INFO juju.environs.sync sync.go:96 listing target tools storage
2014-08-04 16:35:03 DEBUG juju.environs.tools storage.go:35 reading v1.* tools
2014-08-04 16:35:03 INFO juju.environs.sync sync.go:109 found 0 tools in target; 3 tools to be copied
2014-08-04 16:35:03 INFO juju.environs.sync sync.go:149 copying 1.18.4.1-precise-amd64 from file:///tmp/791701108/tools/releases/juju-1.18.4.1-precise-amd64.tgz
2014-08-04 16:35:03 INFO juju.environs.sync sync.go:163 copying tools/releases/juju-1.18.4.1-precise-amd64.tgz
2014-08-04 16:35:03 INFO juju.utils http.go:55 hostname SSL verification enabled
2014-08-04 16:35:03 INFO juju.environs.sync sync.go:176 downloaded tools/releases/juju-1.18.4.1-precise-amd64.tgz (7186kB), uploading
2014-08-04 16:35:03 INFO juju.environs.sync sync.go:177 download 7186kB, uploading
2014-08-04 16:35:04 INFO juju.environs.sync sync.go:149 copying 1.18.4.1-trusty-amd64 from file:///tmp/791701108/tools/releases/juju-1.18.4.1-trusty-amd64.tgz
2014-08-04 16:35:04 INFO juju.environs.sync sync.go:163 copying tools/releases/juju-1.18.4.1-trusty-amd64.tgz
2014-08-04 16:35:04 INFO juju.utils http.go:55 hostname SSL verification enabled
2014-08-04 16:35:04 INFO juju.environs.sync sync.go:176 downloaded tools/releases/juju-1.18.4.1-trusty-amd64.tgz (7186kB), uploading
2014-08-04 16:35:04 INFO juju.environs.sync sync.go:177 download 7186kB, uploading
2014-08-04 16:35:05 INFO juju.cmd cmd.go:113 Bootstrap failed, destroying environment
2014-08-04 16:35:05 INFO juju.provider.common destroy.go:14 destroying environment "maas"
2014-08-04 16:35:06 ERROR juju.cmd supercommand.go:305 gomaasapi: got error back from server: 500 INTERNAL SERVER ERROR (could not serialize access due to read/write dependencies among transactions
DETAIL:  Reason code: Canceled on identification as a pivot, during conflict out checking.
HINT:  The transaction might succeed if retried.
)
}}}

juju --debug status: {{{
2014-08-04 17:24:21 INFO juju.cmd supercommand.go:302 running juju-1.18.4-utopic-amd64 [gc]
2014-08-04 17:24:21 DEBUG juju api.go:179 no cached API connection settings found
2014-08-04 17:24:21 DEBUG juju.provider.maas environprovider.go:30 opening environment "maas".
2014-08-04 17:24:21 ERROR juju.cmd supercommand.go:305 Unable to connect to environment "maas".
Please check your credentials or use 'juju bootstrap' to create a new environment.

Error details:
environment is not bootstrapped
}}}

Traceback (most recent call last):
  File "/tmp/adt-run.IRRYBF/ubtree0-build/real-tree/debian/tests/utils.py", line 70, in wrapper
    result = func(*args, **kwargs)
  File "/tmp/adt-run.IRRYBF/ubtree0-build/real-tree/debian/tests/maas-integration.py", line 742, in test_juju_bootstrap
    self._wait_machines_running(1)
  File "/tmp/adt-run.IRRYBF/ubtree0-build/real-tree/debian/tests/maas-integration.py", line 756, in _wait_machines_running
    sleep(20)
  File "/tmp/adt-run.IRRYBF/ubtree0-build/real-tree/debian/tests/utils.py", line 64, in _handle_timeout
    raise TimeoutError(error_message)
TimeoutError: Timer expired


maas-integration.TestMAASIntegration.test_update_preseed_arm: 0.0002s
maas-integration.TestMAASIntegration.test_check_initial_services: 0.0817s
maas-integration.TestMAASIntegration.test_create_admin: 0.1221s
maas-integration.TestMAASIntegration.test_list_zones: 0.1978s
maas-integration.TestMAASIntegration.test_apply_tag_to_all_nodes: 0.2434s
maas-integration.TestMAASIntegration.test_check_tag_applied_to_all_nodes: 0.3850s
maas-integration.TestMAASIntegration.test_delete_zone: 0.4048s
maas-integration.TestMAASIntegration.test_add_new_zones: 0.4168s
maas-integration.TestMAASIntegration.test_login_api: 0.4451s
maas-integration.TestMAASIntegration.test_import_boot_images: 0.4543s
maas-integration.TestMAASIntegration.test_commission_nodes: 0.4758s
maas-integration.TestMAASIntegration.test_set_http_proxy: 0.7256s
maas-integration.TestMAASIntegration.test_boot_nodes_enlist: 0.8387s
maas-integration.TestMAASIntegration.test_restart_provisioning_server: 1.0670s
maas-integration.TestMAASIntegration.test_update_dns_config: 1.0835s
maas-integration.TestMAASIntegration.test_update_maas_url: 1.1296s
maas-integration.TestMAASIntegration.test_assign_nodes_to_test_zone: 1.2878s
maas-integration.TestMAASIntegration.test_set_nodes_ipmi_config: 3.5303s
maas-integration.TestMAASIntegration.test_check_dhcp_service: 5.0565s
maas-integration.TestMAASIntegration.test_configure_juju: 6.3875s
maas-integration.TestMAASIntegration.test_set_up_dhcp_region: 10.6986s
maas-integration.TestMAASIntegration.test_cluster_connected: 51.2957s
maas-integration.TestMAASIntegration.test_check_nodes_declared: 195.9411s
maas-integration.TestMAASIntegration.test_check_nodes_ready: 222.3092s
maas-integration.TestMAASIntegration.test_imported_boot_images: 834.0870s
maas-integration.TestMAASIntegration.test_juju_bootstrap: 3000.0250s
----------------------------------------------------------------------
Ran 28 tests in 4339.366s

FAILED (SKIP=3, errors=1)
adt-run1: testbed command exited with code 1
adt-run [2014-08-04 13:24:28]: & ubtree0t-maas-package-test: -----------------------]
adt-run1: testbed executing test finished with exit status 1
adt-run2: >> copyup /tmp/adt-run.756aj1/ubtree0t-maas-package-test-stdout /home/ubuntu/jenkins-jobs/workspace/utopic-adt-maas/results/ubtree0t-maas-package-test-stdout
adt-run2: << ok
adt-run2: >> copyup /tmp/adt-run.756aj1/ubtree0t-maas-package-test-stderr /home/ubuntu/jenkins-jobs/workspace/utopic-adt-maas/results/ubtree0t-maas-package-test-stderr
adt-run2: << ok
adt-run: & ubtree0t-maas-package-test:  - - - - - - - - - - results - - - - - - - - - -
ubtree0t-maas-package-test FAIL non-zero exit status 1
adt-run2: >> copyup /tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts/ /home/ubuntu/jenkins-jobs/workspace/utopic-adt-maas/results/artifacts/
adt-run2: << ok
adt-run1: testbed command ['rm', '-rf', u'/tmp/adt-run.756aj1/ubtree0t-maas-package-test-artifacts'], kind short, sout raw, serr pipe, env ['LANG=C']
adt-run1: testbed command exited with code 0
adt-run1: ** needs_reset, previously=False, requested by run_tests() line 1231
adt-run1: ** needs_reset, previously=True, requested by process_actions() line 2126
adt-run: @@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ tests done.
adt-run1: ** stop
adt-run1: ** close, scratch=/tmp/adt-run.756aj1
adt-run2: >> close
qemu: terminating on signal 15 from pid 26205
adt-run2: << ok
adt-run2: >> quit
adt-run2: / tests-tree rmtree /home/ubuntu/jenkins-jobs/workspace/utopic-adt-maas/results/ubtree0-tests-tree
Build step 'Execute shell' marked build as failure
Archiving artifacts
Email was triggered for: Failure
Sending email for trigger: Failure