← Back to team overview

maas-builds team mailing list archive

maas-utopic-trunk - Build # 262 - Still Failing! - Built with lp:maas (revno: 3783) and lp:~maas-maintainers/maas/packaging (revno: 375)

 

Built with lp:maas (revno: 3783) and lp:~maas-maintainers/maas/packaging (revno: 375)

See http://162.213.35.104:8080/job/maas-utopic-trunk/262/

[...truncated 11402 lines...]
tar: ./src/provisioningserver/path.py: time stamp 2015-04-07 10:52:26 is 13196.489301959 s in the future
tar: ./src/provisioningserver/__init__.py: time stamp 2015-04-07 10:52:26 is 13196.488704497 s in the future
tar: ./src/provisioningserver/pserv_services/node_power_monitor_service.py: time stamp 2015-04-07 10:52:26 is 13196.488082546 s in the future
tar: ./src/provisioningserver/pserv_services/dhcp_probe_service.py: time stamp 2015-04-07 10:52:26 is 13196.487450481 s in the future
tar: ./src/provisioningserver/pserv_services/image.py: time stamp 2015-04-07 10:52:26 is 13196.486866384 s in the future
tar: ./src/provisioningserver/pserv_services/tests/test_tftp.py: time stamp 2015-04-07 10:52:26 is 13196.486151082 s in the future
tar: ./src/provisioningserver/pserv_services/tests/test_image_download_service.py: time stamp 2015-04-07 10:52:26 is 13196.485604398 s in the future
tar: ./src/provisioningserver/pserv_services/tests/test_lease_upload_service.py: time stamp 2015-04-07 10:52:26 is 13196.485031698 s in the future
tar: ./src/provisioningserver/pserv_services/tests/test_node_power_monitor_service.py: time stamp 2015-04-07 10:52:26 is 13196.484484585 s in the future
tar: ./src/provisioningserver/pserv_services/tests/__init__.py: time stamp 2015-04-07 10:52:26 is 13196.483876031 s in the future
tar: ./src/provisioningserver/pserv_services/tests/test_dhcp_probe_service.py: time stamp 2015-04-07 10:52:26 is 13196.483179476 s in the future
tar: ./src/provisioningserver/pserv_services/tests: time stamp 2015-04-07 10:52:27 is 13197.482567382 s in the future
tar: ./src/provisioningserver/pserv_services/tftp.py: time stamp 2015-04-07 10:52:26 is 13196.481883216 s in the future
tar: ./src/provisioningserver/pserv_services/image_download_service.py: time stamp 2015-04-07 10:52:26 is 13196.481288732 s in the future
tar: ./src/provisioningserver/pserv_services/__init__.py: time stamp 2015-04-07 10:52:26 is 13196.480693613 s in the future
tar: ./src/provisioningserver/pserv_services/lease_upload_service.py: time stamp 2015-04-07 10:52:26 is 13196.480055775 s in the future
tar: ./src/provisioningserver/pserv_services: time stamp 2015-04-07 10:52:27 is 13197.47942138 s in the future
tar: ./src/provisioningserver/auth.py: time stamp 2015-04-07 10:52:26 is 13196.478839792 s in the future
tar: ./src/provisioningserver/tags.py: time stamp 2015-04-07 10:52:26 is 13196.478196343 s in the future
tar: ./src/provisioningserver/plugin.py: time stamp 2015-04-07 10:52:26 is 13196.477595376 s in the future
tar: ./src/provisioningserver/udev.py: time stamp 2015-04-07 10:52:26 is 13196.477018921 s in the future
tar: ./src/provisioningserver/cluster_config.py: time stamp 2015-04-07 10:52:26 is 13196.476411087 s in the future
tar: ./src/provisioningserver/config.py: time stamp 2015-04-07 10:52:26 is 13196.475726981 s in the future
tar: ./src/provisioningserver/network.py: time stamp 2015-04-07 10:52:26 is 13196.475109321 s in the future
tar: ./src/provisioningserver/logger/tests/__init__.py: time stamp 2015-04-07 10:52:26 is 13196.474464403 s in the future
tar: ./src/provisioningserver/logger/tests/test_logger.py: time stamp 2015-04-07 10:52:26 is 13196.473867959 s in the future
tar: ./src/provisioningserver/logger/tests: time stamp 2015-04-07 10:52:27 is 13197.47326077 s in the future
tar: ./src/provisioningserver/logger/__init__.py: time stamp 2015-04-07 10:52:26 is 13196.472560532 s in the future
tar: ./src/provisioningserver/logger/log.py: time stamp 2015-04-07 10:52:26 is 13196.471741371 s in the future
tar: ./src/provisioningserver/logger: time stamp 2015-04-07 10:52:26 is 13196.47119234 s in the future
tar: ./src/provisioningserver/events.py: time stamp 2015-04-07 10:52:26 is 13196.470601831 s in the future
tar: ./src/provisioningserver/diskless.py: time stamp 2015-04-07 10:52:26 is 13196.469937567 s in the future
tar: ./src/provisioningserver: time stamp 2015-04-07 10:52:28 is 13198.46933963 s in the future
tar: ./required-packages/trusty: time stamp 2015-04-07 10:52:26 is 13196.468738176 s in the future
tar: ./required-packages/dev: time stamp 2015-04-07 10:52:26 is 13196.468072177 s in the future
tar: ./required-packages/doc: time stamp 2015-04-07 10:52:26 is 13196.467483082 s in the future
tar: ./required-packages/vivid: time stamp 2015-04-07 10:52:26 is 13196.466884298 s in the future
tar: ./required-packages/build: time stamp 2015-04-07 10:52:26 is 13196.466302501 s in the future
tar: ./required-packages/optional: time stamp 2015-04-07 10:52:26 is 13196.465724119 s in the future
tar: ./required-packages/base: time stamp 2015-04-07 10:52:26 is 13196.465130025 s in the future
tar: ./required-packages/forbidden: time stamp 2015-04-07 10:52:26 is 13196.464520094 s in the future
tar: ./required-packages/utopic: time stamp 2015-04-07 10:52:26 is 13196.463924025 s in the future
tar: ./required-packages: time stamp 2015-04-07 10:52:28 is 13198.463369034 s in the future
tar: ./README: time stamp 2015-04-07 10:52:26 is 13196.462733739 s in the future
tar: ./utilities/normalize-spelling: time stamp 2015-04-07 10:52:26 is 13196.462055665 s in the future
tar: ./utilities/rewrite-future-imports: time stamp 2015-04-07 10:52:26 is 13196.461454991 s in the future
tar: ./utilities/find-imported-module-files: time stamp 2015-04-07 10:52:26 is 13196.460831755 s in the future
tar: ./utilities/format-new-and-modified-imports: time stamp 2015-04-07 10:52:26 is 13196.460256935 s in the future
tar: ./utilities/python_standard_libs.py: time stamp 2015-04-07 10:52:26 is 13196.45961696 s in the future
tar: ./utilities/doc-lint: time stamp 2015-04-07 10:52:26 is 13196.458964356 s in the future
tar: ./utilities/generate-and-upload-docs: time stamp 2015-04-07 10:52:26 is 13196.458357827 s in the future
tar: ./utilities/format-imports: time stamp 2015-04-07 10:52:26 is 13196.457717149 s in the future
tar: ./utilities/find-imported-modules: time stamp 2015-04-07 10:52:26 is 13196.456134005 s in the future
tar: ./utilities/check-maaslog-exception: time stamp 2015-04-07 10:52:26 is 13196.455334365 s in the future
tar: ./utilities: time stamp 2015-04-07 10:52:28 is 13198.454634102 s in the future
tar: ./LICENSE: time stamp 2015-04-07 10:52:26 is 13196.453940121 s in the future
tar: ./contrib/maas_local_settings.py: time stamp 2015-04-07 10:52:26 is 13196.453308146 s in the future
tar: ./contrib/maas-rsyslog.conf: time stamp 2015-04-07 10:52:26 is 13196.452662146 s in the future
tar: ./contrib/maas-http.conf: time stamp 2015-04-07 10:52:26 is 13196.452026713 s in the future
tar: ./contrib/tgt.conf: time stamp 2015-04-07 10:52:26 is 13196.451425012 s in the future
tar: ./contrib/preseeds_v2/preseed_master: time stamp 2015-04-07 10:52:26 is 13196.4507814 s in the future
tar: ./contrib/preseeds_v2/commissioning: time stamp 2015-04-07 10:52:26 is 13196.450170446 s in the future
tar: ./contrib/preseeds_v2/generic: time stamp 2015-04-07 10:52:26 is 13196.449545685 s in the future
tar: ./contrib/preseeds_v2/preseed_master_windows_amd64_generic_win2012: time stamp 2015-04-07 10:52:26 is 13196.448846055 s in the future
tar: ./contrib/preseeds_v2/curtin: time stamp 2015-04-07 10:52:26 is 13196.448247418 s in the future
tar: ./contrib/preseeds_v2/enlist_userdata: time stamp 2015-04-07 10:52:26 is 13196.447570904 s in the future
tar: ./contrib/preseeds_v2/preseed_master_windows_amd64_generic_win2012hv: time stamp 2015-04-07 10:52:26 is 13196.44669944 s in the future
tar: ./contrib/preseeds_v2/enlist: time stamp 2015-04-07 10:52:26 is 13196.446085511 s in the future
tar: ./contrib/preseeds_v2/curtin_userdata: time stamp 2015-04-07 10:52:26 is 13196.445482195 s in the future
tar: ./contrib/preseeds_v2/curtin_userdata_centos: time stamp 2015-04-07 10:52:26 is 13196.444862299 s in the future
tar: ./contrib/preseeds_v2/curtin_userdata_custom: time stamp 2015-04-07 10:52:26 is 13196.444262278 s in the future
tar: ./contrib/preseeds_v2/preseed_master_windows_amd64_generic_win2012r2: time stamp 2015-04-07 10:52:26 is 13196.443642969 s in the future
tar: ./contrib/preseeds_v2/curtin_userdata_suse: time stamp 2015-04-07 10:52:26 is 13196.443052528 s in the future
tar: ./contrib/preseeds_v2/curtin_userdata_windows: time stamp 2015-04-07 10:52:26 is 13196.442495927 s in the future
tar: ./contrib/preseeds_v2/preseed_master_windows_amd64_generic_win2012hvr2: time stamp 2015-04-07 10:52:26 is 13196.441994165 s in the future
tar: ./contrib/preseeds_v2: time stamp 2015-04-07 10:52:27 is 13197.441498509 s in the future
tar: ./templates/model.py: time stamp 2015-04-07 10:52:26 is 13196.44098616 s in the future
tar: ./templates/test_module.js: time stamp 2015-04-07 10:52:26 is 13196.440431402 s in the future
tar: ./templates/module.py: time stamp 2015-04-07 10:52:26 is 13196.439947971 s in the future
tar: ./templates/script.py: time stamp 2015-04-07 10:52:26 is 13196.439373039 s in the future
tar: ./templates/test_module.py: time stamp 2015-04-07 10:52:26 is 13196.438944646 s in the future
tar: ./templates/management_command.py: time stamp 2015-04-07 10:52:26 is 13196.438497084 s in the future
tar: ./templates/module.js: time stamp 2015-04-07 10:52:26 is 13196.43806709 s in the future
tar: ./templates/doc.txt: time stamp 2015-04-07 10:52:26 is 13196.437603108 s in the future
tar: ./templates/test_module.html: time stamp 2015-04-07 10:52:26 is 13196.43720033 s in the future
tar: ./templates/script.sh: time stamp 2015-04-07 10:52:26 is 13196.436720067 s in the future
tar: ./templates/README: time stamp 2015-04-07 10:52:26 is 13196.436318651 s in the future
tar: ./templates: time stamp 2015-04-07 10:52:28 is 13198.4359022 s in the future
tar: ./versions.cfg: time stamp 2015-04-07 10:52:26 is 13196.435506494 s in the future
tar: ./INSTALL.txt: time stamp 2015-04-07 10:52:26 is 13196.4349713 s in the future
tar: ./src/metadataserver/deployment/maas_configure_interfaces.py: time stamp 2015-04-07 10:52:25 is 13195.434529008 s in the future
tar: ./docs/hacking.rst: time stamp 2015-04-07 10:52:25 is 13195.433924205 s in the future
tar: ./docs/install.rst: time stamp 2015-04-07 10:52:25 is 13195.433530818 s in the future
tar: ./services/regiond2/run: time stamp 2015-04-07 10:52:25 is 13195.433034196 s in the future
tar: ./src/metadataserver/deployment: time stamp 2015-04-07 10:52:26 is 13196.432574607 s in the future
tar: ./src/metadataserver: time stamp 2015-04-07 10:52:28 is 13198.432027781 s in the future
tar: ./docs: time stamp 2015-04-07 10:52:28 is 13198.431426527 s in the future
tar: ./services/regiond2: time stamp 2015-04-07 10:52:26 is 13196.431000319 s in the future
tar: ./services: time stamp 2015-04-07 10:52:28 is 13198.430635255 s in the future
adt-run: DBG: got reply from testbed: ok
adt-run: DBG: testbed command ['chown', '-R', 'ubuntu', '--', '/tmp/adt-run.AwZQIw/build.ViQ/real-tree'], kind short, sout raw, serr pipe, env ['LANG=C.UTF-8']
adt-run: DBG: testbed command exited with code 0
adt-run [11:12:30]: test maas-package-test: [-----------------------
adt-run: DBG: testbed command ['su', '-s', '/bin/bash', 'root', '-c', 'set -e; export USER=`id -nu`; . /etc/profile >/dev/null 2>&1 || true;  . ~/.profile >/dev/null 2>&1 || true; buildtree="/tmp/adt-run.AwZQIw/build.ViQ/real-tree"; mkdir -p -m 1777 -- "/tmp/adt-run.AwZQIw/maas-package-test-artifacts"; export ADT_ARTIFACTS="/tmp/adt-run.AwZQIw/maas-package-test-artifacts"; export ADTTMP=$(mktemp -d --tmpdir adttmp.XXXXXX); rm -f /tmp/adt_test_script_pid; set -C; echo $$ > /tmp/adt_test_script_pid; set +C; trap "rm -rf $ADTTMP /tmp/adt_test_script_pid" EXIT INT QUIT PIPE; chmod 755 $ADTTMP; cd "$buildtree"; export LANG=C.UTF-8; unset LANGUAGE LC_CTYPE LC_NUMERIC LC_TIME LC_COLLATE LC_MONETARY LC_MESSAGES LC_PAPER LC_NAME LC_ADDRESS LC_TELEPHONE LC_MEASUREMENT LC_IDENTIFICATION LC_ALL;chmod +x /tmp/adt-run.AwZQIw/build.ViQ/real-tree/debian/tests/maas-package-test; touch /tmp/adt-run.AwZQIw/maas-package-test-stdout /tmp/adt-run.AwZQIw/maas-package-test-stderr; /tmp/adt-run.AwZQIw/build.ViQ/real-tree/debian/tests/maas-package-test 2> >(tee -a /tmp/adt-run.AwZQIw/maas-package-test-stderr >&2) > >(tee -a /tmp/adt-run.AwZQIw/maas-package-test-stdout); '], kind test, sout raw, serr raw, env ['LANG=C.UTF-8']
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_resources ... ok
maas-integration.TestMAASIntegration.test_imported_boot_resources ... ok
maas-integration.TestMAASIntegration.test_import_boot_images ... SKIP: Don't call import boot images on 1.7 and higher.
maas-integration.TestMAASIntegration.test_imported_boot_images ... ok
maas-integration.TestMAASIntegration.test_boot_nodes_enlist ... ok
maas-integration.TestMAASIntegration.test_check_nodes_declared ... ERROR
SKIP: Not testing Cluster controller

======================================================================
ERROR: maas-integration.TestMAASIntegration.test_check_nodes_declared
----------------------------------------------------------------------
_StringException: Empty attachments:
  stderr for maas maas ['nodes', 'list']

retcode for maas maas ['nodes', 'list']: {{{0}}}
stdout for maas maas ['nodes', 'list']: {{{
[
    {
        "ip_addresses": [], 
        "cpu_count": 0, 
        "power_type": "ipmi", 
        "tag_names": [], 
        "swap_size": null, 
        "owner": null, 
        "macaddress_set": [
            {
                "resource_uri": "/MAAS/api/1.0/nodes/node-e9e8631c-dd17-11e4-b549-deadbeef6bb3/macs/00%3Ae0%3A81%3Add%3Ad4%3A11/", 
                "mac_address": "00:e0:81:dd:d4:11"
            }, 
            {
                "resource_uri": "/MAAS/api/1.0/nodes/node-e9e8631c-dd17-11e4-b549-deadbeef6bb3/macs/00%3Ae0%3A81%3Add%3Ad4%3A12/", 
                "mac_address": "00:e0:81:dd:d4:12"
            }
        ], 
        "zone": {
            "resource_uri": "/MAAS/api/1.0/zones/default/", 
            "name": "default", 
            "description": ""
        }, 
        "hostname": "threadbare-truck.maas", 
        "storage": 0, 
        "system_id": "node-e9e8631c-dd17-11e4-b549-deadbeef6bb3", 
        "power_state": "off", 
        "memory": 0, 
        "disable_ipv4": false, 
        "status": 0, 
        "routers": null, 
        "netboot": true, 
        "osystem": "", 
        "substatus": 0, 
        "architecture": "amd64/generic", 
        "distro_series": "", 
        "resource_uri": "/MAAS/api/1.0/nodes/node-e9e8631c-dd17-11e4-b549-deadbeef6bb3/"
    }
]
}}}

wait_4_0: {{{Waiting for 4 node(s) with status 0.}}}

Traceback (most recent call last):
  File "/tmp/adt-run.AwZQIw/build.ViQ/real-tree/debian/tests/timeout.py", line 22, in wrapper
    result = func(*args, **kwargs)
  File "/tmp/adt-run.AwZQIw/build.ViQ/real-tree/debian/tests/maas-integration.py", line 737, in test_check_nodes_declared
    self._wait_nodes(0)
  File "/tmp/adt-run.AwZQIw/build.ViQ/real-tree/debian/tests/maas-integration.py", line 731, in _wait_nodes
    sleep(5)
  File "/tmp/adt-run.AwZQIw/build.ViQ/real-tree/debian/tests/timeout.py", line 16, in _handle_timeout
    raise TimeoutError(error_message)
TimeoutError: Timer expired


maas-integration.TestMAASIntegration.test_update_preseed_arm: 0.0004s
maas-integration.TestMAASIntegration.test_check_dhcp_service: 0.0114s
maas-integration.TestMAASIntegration.test_check_initial_services: 0.0227s
maas-integration.TestMAASIntegration.test_restart_provisioning_server: 0.0938s
maas-integration.TestMAASIntegration.test_create_admin: 0.1246s
maas-integration.TestMAASIntegration.test_list_zones: 0.2426s
maas-integration.TestMAASIntegration.test_import_boot_resources: 0.2565s
maas-integration.TestMAASIntegration.test_login_api: 0.3040s
maas-integration.TestMAASIntegration.test_boot_nodes_enlist: 0.4244s
maas-integration.TestMAASIntegration.test_cluster_connected: 0.4656s
maas-integration.TestMAASIntegration.test_delete_zone: 0.4829s
maas-integration.TestMAASIntegration.test_add_new_zones: 0.5112s
maas-integration.TestMAASIntegration.test_set_http_proxy: 1.0358s
maas-integration.TestMAASIntegration.test_update_dns_config: 1.0722s
maas-integration.TestMAASIntegration.test_update_maas_url: 2.1936s
maas-integration.TestMAASIntegration.test_set_up_dhcp_region: 13.3009s
maas-integration.TestMAASIntegration.test_imported_boot_resources: 50.0060s
maas-integration.TestMAASIntegration.test_imported_boot_images: 132.5816s
maas-integration.TestMAASIntegration.test_check_nodes_declared: 420.0106s
----------------------------------------------------------------------
Ran 22 tests in 626.648s

FAILED (SKIP=4, errors=1)
adt-run: DBG: testbed command exited with code 1
adt-run [11:23:03]: test maas-package-test: -----------------------]
adt-run: DBG: testbed executing test finished with exit status 1
adt-run: DBG: sending command to testbed: copyup /tmp/adt-run.AwZQIw/maas-package-test-stdout /var/lib/jenkins/workspace/maas-utopic-trunk/results/maas-package-test-stdout
adt-run: DBG: got reply from testbed: ok
adt-run: DBG: sending command to testbed: copyup /tmp/adt-run.AwZQIw/maas-package-test-stderr /var/lib/jenkins/workspace/maas-utopic-trunk/results/maas-package-test-stderr
adt-run: DBG: got reply from testbed: ok
adt-run [11:23:05]: test maas-package-test:  - - - - - - - - - - results - - - - - - - - - -
maas-package-test    FAIL non-zero exit status 1
adt-run: DBG: sending command to testbed: copyup /tmp/adt-run.AwZQIw/maas-package-test-artifacts/ /var/lib/jenkins/workspace/maas-utopic-trunk/results/artifacts/
adt-run: DBG: got reply from testbed: ok
adt-run: DBG: testbed command ['rm', '-rf', '/tmp/adt-run.AwZQIw/maas-package-test-artifacts'], kind short, sout raw, serr pipe, env ['LANG=C.UTF-8']
adt-run: DBG: testbed command exited with code 0
adt-run: DBG: no need to restore click AppArmor profiles
adt-run: DBG: needs_reset, previously=False, requested by run_tests() line 1287
adt-run: DBG: testbed stop
adt-run: DBG: testbed close, scratch=/tmp/adt-run.AwZQIw
adt-run: DBG: sending command to testbed: close
qemu: terminating on signal 15 from pid 12143
adt-run: DBG: got reply from testbed: ok
adt-run: DBG: sending command to testbed: quit
adt-run: DBG: / tests-tree rmtree /var/lib/jenkins/workspace/maas-utopic-trunk/results/tests-tree
Build step 'Execute shell' marked build as failure
Archiving artifacts
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


Follow ups

References