builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-043 starttime: 1473504211.53 results: success (0) buildid: 20160910004004 builduid: 94c0de8f63194c5890e8ffe344faa424 revision: 28878b37a89e309b3311bc9cc6aa864a74a96d3e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:31.532696) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:31.533332) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:31.533686) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:31.585609) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:31.585929) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1473504100.924780-978638062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-10 03:43:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.5M=0s 2016-09-10 03:43:32 (38.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141672 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:31.744996) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:31.745323) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1473504100.924780-978638062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015641 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:31.796993) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:31.797348) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 28878b37a89e309b3311bc9cc6aa864a74a96d3e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 28878b37a89e309b3311bc9cc6aa864a74a96d3e --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1473504100.924780-978638062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-10 03:43:32,535 truncating revision to first 12 chars 2016-09-10 03:43:32,535 Setting DEBUG logging. 2016-09-10 03:43:32,535 attempt 1/10 2016-09-10 03:43:32,535 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/28878b37a89e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-10 03:43:32,878 unpacking tar archive at: mozilla-aurora-28878b37a89e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.716928 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:32.532169) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:32.532589) ========= script_repo_revision: 28878b37a89e309b3311bc9cc6aa864a74a96d3e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:32.533154) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:32.533565) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 03:43:32.543769) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-10 03:43:32.544093) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1473504100.924780-978638062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:43:33 INFO - MultiFileLogger online at 20160910 03:43:33 in /builds/slave/test 03:43:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:43:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:43:33 INFO - {'append_to_log': False, 03:43:33 INFO - 'base_work_dir': '/builds/slave/test', 03:43:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:43:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:43:33 INFO - 'branch': 'Mozilla-Aurora', 03:43:33 INFO - 'buildbot_json_path': 'buildprops.json', 03:43:33 INFO - 'config_files': ('talos/linux_config.py',), 03:43:33 INFO - 'default_actions': ('clobber', 03:43:33 INFO - 'read-buildbot-config', 03:43:33 INFO - 'download-and-extract', 03:43:33 INFO - 'populate-webroot', 03:43:33 INFO - 'create-virtualenv', 03:43:33 INFO - 'install', 03:43:33 INFO - 'run-tests'), 03:43:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:43:33 INFO - 'download_minidump_stackwalk': True, 03:43:33 INFO - 'download_symbols': 'ondemand', 03:43:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:43:33 INFO - 'tooltool.py': '/tools/tooltool.py', 03:43:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:43:33 INFO - '/tools/misc-python/virtualenv.py')}, 03:43:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:43:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:43:33 INFO - 'installer_path': 'installer.exe', 03:43:33 INFO - 'log_level': 'info', 03:43:33 INFO - 'log_name': 'talos', 03:43:33 INFO - 'log_to_console': True, 03:43:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:43:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:43:33 INFO - 'opt_config_files': (), 03:43:33 INFO - 'pip_index': False, 03:43:33 INFO - 'sps_profile': False, 03:43:33 INFO - 'sps_profile_interval': 0, 03:43:33 INFO - 'suite': 'dromaeojs', 03:43:33 INFO - 'system_bits': '32', 03:43:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:43:33 INFO - 'title': 'talos-linux64-ix-043', 03:43:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:43:33 INFO - 'use_talos_json': True, 03:43:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:43:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:43:33 INFO - 'work_dir': 'build'} 03:43:33 INFO - [mozharness: 2016-09-10 10:43:33.375293Z] Running clobber step. 03:43:33 INFO - Running pre-action listener: _resource_record_pre_action 03:43:33 INFO - Running main action method: clobber 03:43:33 INFO - rmtree: /builds/slave/test/build 03:43:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:43:33 INFO - Running post-action listener: _resource_record_post_action 03:43:33 INFO - [mozharness: 2016-09-10 10:43:33.831071Z] Finished clobber step (success) 03:43:33 INFO - [mozharness: 2016-09-10 10:43:33.831171Z] Running read-buildbot-config step. 03:43:33 INFO - Running pre-action listener: _resource_record_pre_action 03:43:33 INFO - Running main action method: read_buildbot_config 03:43:33 INFO - Using buildbot properties: 03:43:33 INFO - { 03:43:33 INFO - "project": "", 03:43:33 INFO - "product": "firefox", 03:43:33 INFO - "script_repo_revision": "production", 03:43:33 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:43:33 INFO - "repository": "", 03:43:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:43:33 INFO - "buildid": "20160910004004", 03:43:33 INFO - "stage_platform": "linux64-pgo", 03:43:33 INFO - "basedir": "/builds/slave/test", 03:43:33 INFO - "nightly_build": "True", 03:43:33 INFO - "buildnumber": 512, 03:43:33 INFO - "slavename": "talos-linux64-ix-043", 03:43:33 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:43:33 INFO - "platform": "ubuntu64_hw", 03:43:33 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:43:33 INFO - "branch": "mozilla-aurora", 03:43:33 INFO - "repo_path": "releases/mozilla-aurora", 03:43:33 INFO - "revision": "28878b37a89e309b3311bc9cc6aa864a74a96d3e", 03:43:33 INFO - "pgo_build": "True", 03:43:33 INFO - "builduid": "94c0de8f63194c5890e8ffe344faa424", 03:43:33 INFO - "slavebuilddir": "test" 03:43:33 INFO - } 03:43:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:43:33 INFO - Running post-action listener: _resource_record_post_action 03:43:33 INFO - [mozharness: 2016-09-10 10:43:33.833823Z] Finished read-buildbot-config step (success) 03:43:33 INFO - [mozharness: 2016-09-10 10:43:33.833913Z] Running download-and-extract step. 03:43:33 INFO - Running pre-action listener: _resource_record_pre_action 03:43:33 INFO - Running main action method: download_and_extract 03:43:33 INFO - mkdir: /builds/slave/test/build/tests 03:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:33 INFO - https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:43:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:43:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:43:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:43:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:43:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:43:33 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:43:33 INFO - retry: Failed, sleeping 30 seconds before retrying 03:44:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:44:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:44:03 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:44:03 INFO - retry: Failed, sleeping 60 seconds before retrying 03:45:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:45:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:45:03 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:45:03 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:45:03 INFO - Caught exception: HTTP Error 404: Not Found 03:45:03 INFO - Caught exception: HTTP Error 404: Not Found 03:45:03 INFO - Caught exception: HTTP Error 404: Not Found 03:45:03 INFO - trying https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:45:03 INFO - Downloading https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:45:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:45:04 INFO - Downloaded 1361 bytes. 03:45:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:45:04 INFO - Using the following test package requirements: 03:45:04 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:45:04 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:45:04 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:45:04 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:45:04 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:45:04 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:45:04 INFO - u'jsshell-linux-x86_64.zip'], 03:45:04 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:45:04 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:45:04 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:45:04 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:45:04 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:45:04 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:45:04 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:45:04 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:45:04 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:45:04 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:45:04 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:45:04 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:04 INFO - https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:45:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:45:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:45:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:45:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:45:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:45:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:45:04 INFO - retry: Failed, sleeping 30 seconds before retrying 03:45:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:45:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:45:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:45:34 INFO - retry: Failed, sleeping 60 seconds before retrying 03:46:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:46:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:46:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:46:34 INFO - Caught exception: HTTP Error 404: Not Found 03:46:34 INFO - Caught exception: HTTP Error 404: Not Found 03:46:34 INFO - Caught exception: HTTP Error 404: Not Found 03:46:34 INFO - trying https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:34 INFO - Downloading https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:46:35 INFO - Downloaded 21768653 bytes. 03:46:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:46:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:46:36 INFO - Return code: 0 03:46:36 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:46:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:36 INFO - https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:46:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:46:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:46:36 INFO - retry: Failed, sleeping 30 seconds before retrying 03:47:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:47:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:47:06 INFO - retry: Failed, sleeping 60 seconds before retrying 03:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:48:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:48:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:48:06 INFO - Caught exception: HTTP Error 404: Not Found 03:48:06 INFO - Caught exception: HTTP Error 404: Not Found 03:48:06 INFO - Caught exception: HTTP Error 404: Not Found 03:48:06 INFO - trying https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:06 INFO - Downloading https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:48:07 INFO - Downloaded 21768653 bytes. 03:48:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:48:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:48:09 INFO - Return code: 0 03:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:09 INFO - https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:48:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:48:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:48:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:48:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:48:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:48:09 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:48:09 INFO - retry: Failed, sleeping 30 seconds before retrying 03:48:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:48:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:48:39 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:48:39 INFO - retry: Failed, sleeping 60 seconds before retrying 03:49:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:49:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:39 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:49:39 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:49:39 INFO - Caught exception: HTTP Error 404: Not Found 03:49:39 INFO - Caught exception: HTTP Error 404: Not Found 03:49:39 INFO - Caught exception: HTTP Error 404: Not Found 03:49:39 INFO - trying https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:39 INFO - Downloading https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:49:40 INFO - Downloaded 14413740 bytes. 03:49:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:49:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:49:40 INFO - Return code: 0 03:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:40 INFO - https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:49:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:49:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:49:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:49:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:49:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:49:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:49:40 INFO - retry: Failed, sleeping 30 seconds before retrying 03:50:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:50:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:50:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:50:10 INFO - retry: Failed, sleeping 60 seconds before retrying 03:51:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:51:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:51:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:51:10 INFO - Caught exception: HTTP Error 404: Not Found 03:51:10 INFO - Caught exception: HTTP Error 404: Not Found 03:51:10 INFO - Caught exception: HTTP Error 404: Not Found 03:51:10 INFO - trying https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:10 INFO - Downloading https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:51:11 INFO - Downloaded 63890420 bytes. 03:51:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:11 INFO - mkdir: /builds/slave/test/properties 03:51:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:51:11 INFO - Writing to file /builds/slave/test/properties/build_url 03:51:11 INFO - Contents: 03:51:11 INFO - build_url:https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:12 INFO - Running post-action listener: _resource_record_post_action 03:51:12 INFO - Running post-action listener: set_extra_try_arguments 03:51:12 INFO - [mozharness: 2016-09-10 10:51:12.189874Z] Finished download-and-extract step (success) 03:51:12 INFO - [mozharness: 2016-09-10 10:51:12.190107Z] Running populate-webroot step. 03:51:12 INFO - Running pre-action listener: _resource_record_pre_action 03:51:12 INFO - Running main action method: populate_webroot 03:51:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:51:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:51:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:51:12 INFO - '--tppagecycles', 03:51:12 INFO - '7'], 03:51:12 INFO - 'tests': ['tsvgm']}}, 03:51:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:51:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:51:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:51:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:51:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:12 INFO - 'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:51:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:51:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:12 INFO - 'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['damp', 'tps']}, 03:51:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:12 INFO - 'tests': ['damp', 'tps']}, 03:51:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['dromaeo_dom']}, 03:51:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:51:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['basic_compositor_video']}, 03:51:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:51:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['a11yr', 03:51:12 INFO - 'ts_paint', 03:51:12 INFO - 'tpaint', 03:51:12 INFO - 'sessionrestore', 03:51:12 INFO - 'sessionrestore_no_auto_restore', 03:51:12 INFO - 'tabpaint']}, 03:51:12 INFO - 'other-e10s': {'tests': ['a11yr', 03:51:12 INFO - 'ts_paint', 03:51:12 INFO - 'tpaint', 03:51:12 INFO - 'sessionrestore', 03:51:12 INFO - 'sessionrestore_no_auto_restore', 03:51:12 INFO - 'tabpaint']}, 03:51:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:51:12 INFO - 'ts_paint', 03:51:12 INFO - 'tpaint', 03:51:12 INFO - 'sessionrestore', 03:51:12 INFO - 'sessionrestore_no_auto_restore', 03:51:12 INFO - 'tabpaint']}, 03:51:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:51:12 INFO - 'ts_paint', 03:51:12 INFO - 'tpaint', 03:51:12 INFO - 'sessionrestore', 03:51:12 INFO - 'sessionrestore_no_auto_restore', 03:51:12 INFO - 'tabpaint']}, 03:51:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['a11yr', 03:51:12 INFO - 'ts_paint', 03:51:12 INFO - 'tpaint', 03:51:12 INFO - 'sessionrestore', 03:51:12 INFO - 'sessionrestore_no_auto_restore', 03:51:12 INFO - 'tabpaint']}, 03:51:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['a11yr', 03:51:12 INFO - 'ts_paint', 03:51:12 INFO - 'tpaint', 03:51:12 INFO - 'sessionrestore', 03:51:12 INFO - 'sessionrestore_no_auto_restore', 03:51:12 INFO - 'tabpaint']}, 03:51:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['tsvgx', 03:51:12 INFO - 'tsvgr_opacity', 03:51:12 INFO - 'tart', 03:51:12 INFO - 'tscrollx', 03:51:12 INFO - 'cart']}, 03:51:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:51:12 INFO - 'tsvgr_opacity', 03:51:12 INFO - 'tart', 03:51:12 INFO - 'tscrollx', 03:51:12 INFO - 'cart']}, 03:51:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:12 INFO - 'talos_options': ['--disable-e10s'], 03:51:12 INFO - 'tests': ['tp5o']}, 03:51:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:12 INFO - 'tests': ['tp5o']}, 03:51:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:12 INFO - 'talos_options': ['--disable-e10s', 03:51:12 INFO - '--xperf_path', 03:51:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:51:12 INFO - 'tests': ['tp5n']}, 03:51:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:12 INFO - 'talos_options': ['--xperf_path', 03:51:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:51:12 INFO - 'tests': ['tp5n']}}, 03:51:12 INFO - 'talos.zip': {'path': '', 03:51:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:51:12 INFO - Running post-action listener: _resource_record_post_action 03:51:12 INFO - [mozharness: 2016-09-10 10:51:12.206971Z] Finished populate-webroot step (success) 03:51:12 INFO - [mozharness: 2016-09-10 10:51:12.207061Z] Running create-virtualenv step. 03:51:12 INFO - Running pre-action listener: _resource_record_pre_action 03:51:12 INFO - Running main action method: create_virtualenv 03:51:12 INFO - Creating virtualenv /builds/slave/test/build/venv 03:51:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:51:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:51:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:51:12 INFO - Using real prefix '/usr' 03:51:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:51:12 INFO - Installing distribute.............................................................................................................................................................................................done. 03:51:14 INFO - Installing pip.................done. 03:51:14 INFO - Return code: 0 03:51:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:51:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:51:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:51:14 INFO - Using env: {'DISPLAY': ':0', 03:51:14 INFO - 'HOME': '/home/cltbld', 03:51:14 INFO - 'LANG': 'en_US.UTF-8', 03:51:14 INFO - 'LANGUAGE': 'en_US:en', 03:51:14 INFO - 'LOGNAME': 'cltbld', 03:51:14 INFO - 'MAIL': '/var/mail/cltbld', 03:51:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:14 INFO - 'MOZ_NO_REMOTE': '1', 03:51:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:14 INFO - 'NO_EM_RESTART': '1', 03:51:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:14 INFO - 'PWD': '/builds/slave/test', 03:51:14 INFO - 'SHELL': '/bin/bash', 03:51:14 INFO - 'SHLVL': '1', 03:51:14 INFO - 'TERM': 'linux', 03:51:14 INFO - 'TMOUT': '86400', 03:51:14 INFO - 'USER': 'cltbld', 03:51:14 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:14 INFO - '_': '/tools/buildbot/bin/python'} 03:51:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:51:14 INFO - Cleaning up... 03:51:14 INFO - Return code: 0 03:51:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:51:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:51:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:51:14 INFO - Using env: {'DISPLAY': ':0', 03:51:14 INFO - 'HOME': '/home/cltbld', 03:51:14 INFO - 'LANG': 'en_US.UTF-8', 03:51:15 INFO - 'LANGUAGE': 'en_US:en', 03:51:15 INFO - 'LOGNAME': 'cltbld', 03:51:15 INFO - 'MAIL': '/var/mail/cltbld', 03:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:15 INFO - 'MOZ_NO_REMOTE': '1', 03:51:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:15 INFO - 'NO_EM_RESTART': '1', 03:51:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:15 INFO - 'PWD': '/builds/slave/test', 03:51:15 INFO - 'SHELL': '/bin/bash', 03:51:15 INFO - 'SHLVL': '1', 03:51:15 INFO - 'TERM': 'linux', 03:51:15 INFO - 'TMOUT': '86400', 03:51:15 INFO - 'USER': 'cltbld', 03:51:15 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:15 INFO - '_': '/tools/buildbot/bin/python'} 03:51:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:15 INFO - Downloading/unpacking psutil>=3.1.1 03:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:51:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:51:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:51:17 INFO - Installing collected packages: psutil 03:51:17 INFO - Running setup.py install for psutil 03:51:17 INFO - building 'psutil._psutil_linux' extension 03:51:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:51:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:51:17 INFO - building 'psutil._psutil_posix' extension 03:51:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:51:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:51:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:51:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:51:17 INFO - Successfully installed psutil 03:51:17 INFO - Cleaning up... 03:51:17 INFO - Return code: 0 03:51:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:51:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:51:17 INFO - Using env: {'DISPLAY': ':0', 03:51:17 INFO - 'HOME': '/home/cltbld', 03:51:17 INFO - 'LANG': 'en_US.UTF-8', 03:51:17 INFO - 'LANGUAGE': 'en_US:en', 03:51:17 INFO - 'LOGNAME': 'cltbld', 03:51:17 INFO - 'MAIL': '/var/mail/cltbld', 03:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:17 INFO - 'MOZ_NO_REMOTE': '1', 03:51:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:17 INFO - 'NO_EM_RESTART': '1', 03:51:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:17 INFO - 'PWD': '/builds/slave/test', 03:51:17 INFO - 'SHELL': '/bin/bash', 03:51:17 INFO - 'SHLVL': '1', 03:51:17 INFO - 'TERM': 'linux', 03:51:17 INFO - 'TMOUT': '86400', 03:51:17 INFO - 'USER': 'cltbld', 03:51:17 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:17 INFO - '_': '/tools/buildbot/bin/python'} 03:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:51:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:51:19 INFO - Installing collected packages: mozsystemmonitor 03:51:19 INFO - Running setup.py install for mozsystemmonitor 03:51:19 INFO - Successfully installed mozsystemmonitor 03:51:19 INFO - Cleaning up... 03:51:20 INFO - Return code: 0 03:51:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:51:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:51:20 INFO - Using env: {'DISPLAY': ':0', 03:51:20 INFO - 'HOME': '/home/cltbld', 03:51:20 INFO - 'LANG': 'en_US.UTF-8', 03:51:20 INFO - 'LANGUAGE': 'en_US:en', 03:51:20 INFO - 'LOGNAME': 'cltbld', 03:51:20 INFO - 'MAIL': '/var/mail/cltbld', 03:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:20 INFO - 'MOZ_NO_REMOTE': '1', 03:51:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:20 INFO - 'NO_EM_RESTART': '1', 03:51:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:20 INFO - 'PWD': '/builds/slave/test', 03:51:20 INFO - 'SHELL': '/bin/bash', 03:51:20 INFO - 'SHLVL': '1', 03:51:20 INFO - 'TERM': 'linux', 03:51:20 INFO - 'TMOUT': '86400', 03:51:20 INFO - 'USER': 'cltbld', 03:51:20 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:20 INFO - '_': '/tools/buildbot/bin/python'} 03:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:20 INFO - Downloading/unpacking jsonschema==2.5.1 03:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:51:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:22 INFO - Downloading functools32-3.2.3-2.tar.gz 03:51:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:51:22 INFO - warning: no files found matching '*.txt' 03:51:22 INFO - no previously-included directories found matching 'build' 03:51:22 INFO - no previously-included directories found matching 'dist' 03:51:22 INFO - no previously-included directories found matching '.git*' 03:51:22 INFO - Installing collected packages: jsonschema, functools32 03:51:22 INFO - Running setup.py install for functools32 03:51:22 INFO - warning: no files found matching '*.txt' 03:51:22 INFO - no previously-included directories found matching 'build' 03:51:22 INFO - no previously-included directories found matching 'dist' 03:51:22 INFO - no previously-included directories found matching '.git*' 03:51:22 INFO - Successfully installed jsonschema functools32 03:51:22 INFO - Cleaning up... 03:51:22 INFO - Return code: 0 03:51:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:51:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:51:22 INFO - Using env: {'DISPLAY': ':0', 03:51:22 INFO - 'HOME': '/home/cltbld', 03:51:22 INFO - 'LANG': 'en_US.UTF-8', 03:51:22 INFO - 'LANGUAGE': 'en_US:en', 03:51:22 INFO - 'LOGNAME': 'cltbld', 03:51:22 INFO - 'MAIL': '/var/mail/cltbld', 03:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:22 INFO - 'MOZ_NO_REMOTE': '1', 03:51:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:22 INFO - 'NO_EM_RESTART': '1', 03:51:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:22 INFO - 'PWD': '/builds/slave/test', 03:51:22 INFO - 'SHELL': '/bin/bash', 03:51:22 INFO - 'SHLVL': '1', 03:51:22 INFO - 'TERM': 'linux', 03:51:22 INFO - 'TMOUT': '86400', 03:51:22 INFO - 'USER': 'cltbld', 03:51:22 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:22 INFO - '_': '/tools/buildbot/bin/python'} 03:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:51:22 INFO - Cleaning up... 03:51:22 INFO - Return code: 0 03:51:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:51:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:51:22 INFO - Using env: {'DISPLAY': ':0', 03:51:22 INFO - 'HOME': '/home/cltbld', 03:51:22 INFO - 'LANG': 'en_US.UTF-8', 03:51:22 INFO - 'LANGUAGE': 'en_US:en', 03:51:22 INFO - 'LOGNAME': 'cltbld', 03:51:22 INFO - 'MAIL': '/var/mail/cltbld', 03:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:22 INFO - 'MOZ_NO_REMOTE': '1', 03:51:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:22 INFO - 'NO_EM_RESTART': '1', 03:51:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:22 INFO - 'PWD': '/builds/slave/test', 03:51:22 INFO - 'SHELL': '/bin/bash', 03:51:22 INFO - 'SHLVL': '1', 03:51:22 INFO - 'TERM': 'linux', 03:51:22 INFO - 'TMOUT': '86400', 03:51:22 INFO - 'USER': 'cltbld', 03:51:22 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:22 INFO - '_': '/tools/buildbot/bin/python'} 03:51:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:23 INFO - Downloading/unpacking blobuploader==1.2.4 03:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:24 INFO - Downloading blobuploader-1.2.4.tar.gz 03:51:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:51:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:51:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:25 INFO - Downloading docopt-0.6.1.tar.gz 03:51:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:51:25 INFO - Installing collected packages: blobuploader, requests, docopt 03:51:25 INFO - Running setup.py install for blobuploader 03:51:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:51:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:51:25 INFO - Running setup.py install for requests 03:51:26 INFO - Running setup.py install for docopt 03:51:26 INFO - Successfully installed blobuploader requests docopt 03:51:26 INFO - Cleaning up... 03:51:26 INFO - Return code: 0 03:51:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:51:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:51:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:51:26 INFO - Using env: {'DISPLAY': ':0', 03:51:26 INFO - 'HOME': '/home/cltbld', 03:51:26 INFO - 'LANG': 'en_US.UTF-8', 03:51:26 INFO - 'LANGUAGE': 'en_US:en', 03:51:26 INFO - 'LOGNAME': 'cltbld', 03:51:26 INFO - 'MAIL': '/var/mail/cltbld', 03:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:26 INFO - 'MOZ_NO_REMOTE': '1', 03:51:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:26 INFO - 'NO_EM_RESTART': '1', 03:51:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:26 INFO - 'PWD': '/builds/slave/test', 03:51:26 INFO - 'SHELL': '/bin/bash', 03:51:26 INFO - 'SHLVL': '1', 03:51:26 INFO - 'TERM': 'linux', 03:51:26 INFO - 'TMOUT': '86400', 03:51:26 INFO - 'USER': 'cltbld', 03:51:26 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:26 INFO - '_': '/tools/buildbot/bin/python'} 03:51:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:51:26 INFO - Running setup.py (path:/tmp/pip-YsUIW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:51:26 INFO - Running setup.py (path:/tmp/pip-jMNDQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:51:26 INFO - Running setup.py (path:/tmp/pip-3P7hUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:51:26 INFO - Running setup.py (path:/tmp/pip-g_W8XO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:51:26 INFO - Running setup.py (path:/tmp/pip-fVxwkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:51:26 INFO - Running setup.py (path:/tmp/pip-hcYpzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:51:26 INFO - Running setup.py (path:/tmp/pip-jdaLRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:51:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:51:26 INFO - Running setup.py (path:/tmp/pip-wZWdj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:51:27 INFO - Running setup.py (path:/tmp/pip-aWePpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:51:27 INFO - Running setup.py (path:/tmp/pip-A_sNXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:51:27 INFO - Running setup.py (path:/tmp/pip-ClpiHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:51:27 INFO - Running setup.py (path:/tmp/pip-WPmYIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:51:27 INFO - Running setup.py (path:/tmp/pip-ukbP8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:51:27 INFO - Running setup.py (path:/tmp/pip-WdIxev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:51:27 INFO - Running setup.py (path:/tmp/pip-fDllct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:51:27 INFO - Running setup.py (path:/tmp/pip-3pijzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:51:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:51:27 INFO - Running setup.py (path:/tmp/pip-svRw1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:51:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:51:27 INFO - Running setup.py install for manifestparser 03:51:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:51:27 INFO - Running setup.py install for mozcrash 03:51:27 INFO - Running setup.py install for mozdebug 03:51:28 INFO - Running setup.py install for mozdevice 03:51:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:51:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:51:28 INFO - Running setup.py install for mozfile 03:51:28 INFO - Running setup.py install for mozhttpd 03:51:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:51:28 INFO - Running setup.py install for mozinfo 03:51:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:51:28 INFO - Running setup.py install for mozInstall 03:51:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:51:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:51:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:51:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:51:28 INFO - Running setup.py install for mozleak 03:51:28 INFO - Running setup.py install for mozlog 03:51:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:51:28 INFO - Running setup.py install for moznetwork 03:51:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:51:28 INFO - Running setup.py install for mozprocess 03:51:28 INFO - Running setup.py install for mozprofile 03:51:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:51:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:51:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:51:29 INFO - Running setup.py install for mozrunner 03:51:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:51:29 INFO - Running setup.py install for mozscreenshot 03:51:29 INFO - Running setup.py install for moztest 03:51:29 INFO - Running setup.py install for mozversion 03:51:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:51:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:51:29 INFO - Cleaning up... 03:51:29 INFO - Return code: 0 03:51:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:51:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:51:29 INFO - Using env: {'DISPLAY': ':0', 03:51:29 INFO - 'HOME': '/home/cltbld', 03:51:29 INFO - 'LANG': 'en_US.UTF-8', 03:51:29 INFO - 'LANGUAGE': 'en_US:en', 03:51:29 INFO - 'LOGNAME': 'cltbld', 03:51:29 INFO - 'MAIL': '/var/mail/cltbld', 03:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:29 INFO - 'MOZ_NO_REMOTE': '1', 03:51:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:29 INFO - 'NO_EM_RESTART': '1', 03:51:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:29 INFO - 'PWD': '/builds/slave/test', 03:51:29 INFO - 'SHELL': '/bin/bash', 03:51:29 INFO - 'SHLVL': '1', 03:51:29 INFO - 'TERM': 'linux', 03:51:29 INFO - 'TMOUT': '86400', 03:51:29 INFO - 'USER': 'cltbld', 03:51:29 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:29 INFO - '_': '/tools/buildbot/bin/python'} 03:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:51:29 INFO - Running setup.py (path:/tmp/pip-g57ZB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:51:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:51:29 INFO - Running setup.py (path:/tmp/pip-0v9GuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:51:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:51:29 INFO - Running setup.py (path:/tmp/pip-qUHN9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:51:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:51:29 INFO - Running setup.py (path:/tmp/pip-ooJ1Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:51:30 INFO - Running setup.py (path:/tmp/pip-WhaC5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:51:30 INFO - Running setup.py (path:/tmp/pip-ViTFBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:51:30 INFO - Running setup.py (path:/tmp/pip-GnB2nh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:51:30 INFO - Running setup.py (path:/tmp/pip-wN5v7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:51:30 INFO - Running setup.py (path:/tmp/pip-txhSPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:51:30 INFO - Running setup.py (path:/tmp/pip-LT1oEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:51:30 INFO - Running setup.py (path:/tmp/pip-QSUapr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:51:30 INFO - Running setup.py (path:/tmp/pip-pYfNL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:51:30 INFO - Running setup.py (path:/tmp/pip-ZkP6If-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:51:30 INFO - Running setup.py (path:/tmp/pip-eMa0Pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:51:30 INFO - Running setup.py (path:/tmp/pip-Cf01wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:51:30 INFO - Running setup.py (path:/tmp/pip-CLIbvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:51:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:51:30 INFO - Running setup.py (path:/tmp/pip-vAcaVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:51:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:51:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:32 INFO - Downloading blessings-1.6.tar.gz 03:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:51:32 INFO - Installing collected packages: blessings 03:51:32 INFO - Running setup.py install for blessings 03:51:33 INFO - Successfully installed blessings 03:51:33 INFO - Cleaning up... 03:51:33 INFO - Return code: 0 03:51:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:51:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:51:33 INFO - Reading from file tmpfile_stdout 03:51:33 INFO - Current package versions: 03:51:33 INFO - argparse == 1.2.1 03:51:33 INFO - blessings == 1.6 03:51:33 INFO - blobuploader == 1.2.4 03:51:33 INFO - docopt == 0.6.1 03:51:33 INFO - functools32 == 3.2.3-2 03:51:33 INFO - jsonschema == 2.5.1 03:51:33 INFO - manifestparser == 1.1 03:51:33 INFO - mozInstall == 1.12 03:51:33 INFO - mozcrash == 0.17 03:51:33 INFO - mozdebug == 0.1 03:51:33 INFO - mozdevice == 0.48 03:51:33 INFO - mozfile == 1.2 03:51:33 INFO - mozhttpd == 0.7 03:51:33 INFO - mozinfo == 0.9 03:51:33 INFO - mozleak == 0.1 03:51:33 INFO - mozlog == 3.3 03:51:33 INFO - moznetwork == 0.27 03:51:33 INFO - mozprocess == 0.23 03:51:33 INFO - mozprofile == 0.28 03:51:33 INFO - mozrunner == 6.12 03:51:33 INFO - mozscreenshot == 0.1 03:51:33 INFO - mozsystemmonitor == 0.3 03:51:33 INFO - moztest == 0.7 03:51:33 INFO - mozversion == 1.4 03:51:33 INFO - psutil == 3.1.1 03:51:33 INFO - requests == 1.2.3 03:51:33 INFO - wsgiref == 0.1.2 03:51:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:51:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:51:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:51:33 INFO - Using env: {'DISPLAY': ':0', 03:51:33 INFO - 'HOME': '/home/cltbld', 03:51:33 INFO - 'LANG': 'en_US.UTF-8', 03:51:33 INFO - 'LANGUAGE': 'en_US:en', 03:51:33 INFO - 'LOGNAME': 'cltbld', 03:51:33 INFO - 'MAIL': '/var/mail/cltbld', 03:51:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:33 INFO - 'MOZ_NO_REMOTE': '1', 03:51:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:33 INFO - 'NO_EM_RESTART': '1', 03:51:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:33 INFO - 'PWD': '/builds/slave/test', 03:51:33 INFO - 'SHELL': '/bin/bash', 03:51:33 INFO - 'SHLVL': '1', 03:51:33 INFO - 'TERM': 'linux', 03:51:33 INFO - 'TMOUT': '86400', 03:51:33 INFO - 'USER': 'cltbld', 03:51:33 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:33 INFO - '_': '/tools/buildbot/bin/python'} 03:51:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:51:33 INFO - Cleaning up... 03:51:33 INFO - Return code: 0 03:51:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:51:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c3f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:51:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:51:33 INFO - Using env: {'DISPLAY': ':0', 03:51:33 INFO - 'HOME': '/home/cltbld', 03:51:33 INFO - 'LANG': 'en_US.UTF-8', 03:51:33 INFO - 'LANGUAGE': 'en_US:en', 03:51:33 INFO - 'LOGNAME': 'cltbld', 03:51:33 INFO - 'MAIL': '/var/mail/cltbld', 03:51:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:33 INFO - 'MOZ_NO_REMOTE': '1', 03:51:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:33 INFO - 'NO_EM_RESTART': '1', 03:51:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:33 INFO - 'PWD': '/builds/slave/test', 03:51:33 INFO - 'SHELL': '/bin/bash', 03:51:33 INFO - 'SHLVL': '1', 03:51:33 INFO - 'TERM': 'linux', 03:51:33 INFO - 'TMOUT': '86400', 03:51:33 INFO - 'USER': 'cltbld', 03:51:33 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:33 INFO - '_': '/tools/buildbot/bin/python'} 03:51:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:51:33 INFO - Cleaning up... 03:51:33 INFO - Return code: 0 03:51:33 INFO - Running post-action listener: _resource_record_post_action 03:51:33 INFO - Running post-action listener: _start_resource_monitoring 03:51:33 INFO - Starting resource monitoring. 03:51:33 INFO - [mozharness: 2016-09-10 10:51:33.775444Z] Finished create-virtualenv step (success) 03:51:33 INFO - [mozharness: 2016-09-10 10:51:33.776030Z] Running install step. 03:51:33 INFO - Running pre-action listener: _resource_record_pre_action 03:51:33 INFO - Running main action method: install 03:51:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:51:33 INFO - Reading from file tmpfile_stdout 03:51:33 INFO - Detecting whether we're running mozinstall >=1.0... 03:51:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:51:34 INFO - Reading from file tmpfile_stdout 03:51:34 INFO - Output received: 03:51:34 INFO - Usage: mozinstall [options] installer 03:51:34 INFO - Options: 03:51:34 INFO - -h, --help show this help message and exit 03:51:34 INFO - -d DEST, --destination=DEST 03:51:34 INFO - Directory to install application into. [default: 03:51:34 INFO - "/builds/slave/test"] 03:51:34 INFO - --app=APP Application being installed. [default: firefox] 03:51:34 INFO - mkdir: /builds/slave/test/build/application 03:51:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:51:50 INFO - Reading from file tmpfile_stdout 03:51:50 INFO - Output received: 03:51:50 INFO - /builds/slave/test/build/application/firefox/firefox 03:51:50 INFO - Running post-action listener: _resource_record_post_action 03:51:50 INFO - [mozharness: 2016-09-10 10:51:50.113355Z] Finished install step (success) 03:51:50 INFO - [mozharness: 2016-09-10 10:51:50.113631Z] Running run-tests step. 03:51:50 INFO - Running pre-action listener: _resource_record_pre_action 03:51:50 INFO - Running main action method: run_tests 03:51:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:51:50 INFO - Python 2.7.3 03:51:50 INFO - Return code: 0 03:51:50 INFO - grabbing minidump binary from tooltool 03:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:50 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3d3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c3e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:51:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:51:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:51:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:51:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:51:50 INFO - Return code: 0 03:51:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:51:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:51:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:51:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:51:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:51:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:51:50 INFO - Using env: {'DISPLAY': ':0', 03:51:50 INFO - 'HOME': '/home/cltbld', 03:51:50 INFO - 'LANG': 'en_US.UTF-8', 03:51:50 INFO - 'LANGUAGE': 'en_US:en', 03:51:50 INFO - 'LOGNAME': 'cltbld', 03:51:50 INFO - 'MAIL': '/var/mail/cltbld', 03:51:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:51:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:50 INFO - 'MOZ_NO_REMOTE': '1', 03:51:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:51:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:51:50 INFO - 'NO_EM_RESTART': '1', 03:51:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:50 INFO - 'PWD': '/builds/slave/test', 03:51:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:51:50 INFO - 'SHELL': '/bin/bash', 03:51:50 INFO - 'SHLVL': '1', 03:51:50 INFO - 'TERM': 'linux', 03:51:50 INFO - 'TMOUT': '86400', 03:51:50 INFO - 'USER': 'cltbld', 03:51:50 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1473504100.924780-978638062', 03:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:50 INFO - '_': '/tools/buildbot/bin/python'} 03:51:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:51:50 INFO - mozversion application_buildid: 20160910004004 03:51:50 INFO - mozversion application_changeset: 28878b37a89e309b3311bc9cc6aa864a74a96d3e 03:51:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:51:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:51:50 INFO - mozversion application_name: Firefox 03:51:50 INFO - mozversion application_remotingname: firefox-dev 03:51:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:51:50 INFO - mozversion application_vendor: Mozilla 03:51:50 INFO - mozversion application_version: 50.0a2 03:51:50 INFO - mozversion platform_buildid: 20160910004004 03:51:50 INFO - mozversion platform_changeset: 28878b37a89e309b3311bc9cc6aa864a74a96d3e 03:51:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:51:50 INFO - mozversion platform_version: 50.0a2 03:51:50 INFO - using testdate: 1473504710 03:51:50 INFO - actual date: 1473504710 03:51:50 INFO - starting webserver on 'localhost:38238' 03:51:50 INFO - SUITE-START | Running 2 tests 03:51:50 INFO - TEST-START | dromaeo_css 03:51:50 INFO - operating with platform_type : linux_ 03:51:50 INFO - Initialising browser for dromaeo_css test... 03:51:50 INFO - TEST-INFO | started process 29036 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_YWHtS/profile http://localhost:38238/getInfo.html) 03:51:54 INFO - PROCESS | 29036 | __metrics Screen width/height:1600/1200 03:51:54 INFO - PROCESS | 29036 | colorDepth:24 03:51:54 INFO - PROCESS | 29036 | Browser inner width/height: 1024/697 03:51:54 INFO - PROCESS | 29036 | __metrics 03:51:55 INFO - PROCESS | 29036 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:51:55 INFO - PROCESS | 29036 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:51:56 INFO - TEST-INFO | 29036: exit 0 03:51:56 INFO - Browser initialized. 03:51:56 INFO - Running cycle 1/1 for dromaeo_css test... 03:51:56 INFO - TEST-INFO | started process 29123 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_YWHtS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:52:08 INFO - PROCESS | 29123 | RSS: Main: 146239488 03:52:08 INFO - PROCESS | 29123 | 03:52:58 INFO - PROCESS | 29123 | 1473504778978 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:55:07 INFO - PROCESS | 29123 | Cycle 1(1): loaded http://localhost:38238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38238/tests/dromaeo/cssquery-ext.html) 03:55:09 INFO - PROCESS | 29123 | RSS: Main: 336388096 03:55:09 INFO - PROCESS | 29123 | 03:58:12 INFO - PROCESS | 29123 | Cycle 1(1): loaded http://localhost:38238/tests/dromaeo/cssquery-ext.html (next: http://localhost:38238/tests/dromaeo/cssquery-jquery.html) 03:58:12 INFO - PROCESS | 29123 | RSS: Main: 239800320 03:58:12 INFO - PROCESS | 29123 | 04:01:14 INFO - PROCESS | 29123 | Cycle 1(1): loaded http://localhost:38238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38238/tests/dromaeo/cssquery-mootools.html) 04:01:15 INFO - PROCESS | 29123 | RSS: Main: 175120384 04:01:15 INFO - PROCESS | 29123 | 04:04:17 INFO - PROCESS | 29123 | Cycle 1(1): loaded http://localhost:38238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38238/tests/dromaeo/cssquery-prototype.html) 04:04:17 INFO - PROCESS | 29123 | RSS: Main: 233529344 04:04:17 INFO - PROCESS | 29123 | 04:07:20 INFO - PROCESS | 29123 | Cycle 1(1): loaded http://localhost:38238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38238/tests/dromaeo/cssquery-yui.html) 04:07:20 INFO - PROCESS | 29123 | RSS: Main: 177946624 04:07:20 INFO - PROCESS | 29123 | 04:10:23 INFO - PROCESS | 29123 | Cycle 1(1): loaded http://localhost:38238/tests/dromaeo/cssquery-yui.html (next: http://localhost:38238/tests/dromaeo/cssquery-dojo.html) 04:10:23 INFO - PROCESS | 29123 | RSS: Main: 264675328 04:10:23 INFO - PROCESS | 29123 | 04:10:23 INFO - PROCESS | 29123 | __start_tp_report 04:10:23 INFO - PROCESS | 29123 | _x_x_mozilla_page_load 04:10:23 INFO - PROCESS | 29123 | _x_x_mozilla_page_load_details 04:10:23 INFO - PROCESS | 29123 | |i|pagename|runs| 04:10:23 INFO - PROCESS | 29123 | |0;dojo.html;3201.4717571600686;3203.6155661320636;3231.854566544504;3242.157039169815;3281.78668386555;5436.75534600943;5518.530924871386;5537.972310138453;5541.9168712469345;5559.11054231323;5488.725563721808;5526.613137080406;5527.917081243774;5532.612717109794;5558.916616250759;4923.163062279403;4965.255211718235;4988.201887697978;5032.798688052473;5080.161773307418;4977.527134922177;5072;5074.720890351029;5077.162268225738;5080.491950804909;5010.323606313155;5037.924431133536;5046.394432668067;5078.339815823938;5085.949140508603;5127.48725127488;5145.794168233266;5149.021685879671;5151.587872970191;5175.24958880962;4914.311996320518;4964.776585053645;5008.098542262392;5062.898742025176;5088.567471764904;5429.321334833146;5441.374241962186;5447.210154527611;5459.454054594548;5466.207399927028;5902.439268269551;5915.467607915265;5923.17075609415;5927.407259274038;5930.7924222652;5682.801101961411;5972.581919265654;5999.460048595603;5999.790007349722;6032.426919442646;5651.011073062239;5654.321481422174;5664.20701101846;5671.234383358235;5700.344460387026;6186.566940314225;6190.288116866618;6191.504679625619;6196.349383314777;6196.845078872974;5587.44125587438;5833.095870140135;5853.356130825606;5864.44287792659;5960.254968128984;5985.521158307325;5987.520998320124;5991.340952495222;5992.970035149883;5993.580449368589;5956.583039187302;5965.224520812267;5966.910496342558;5967.761289548456;5968.1942937703725;5732.942670573324;5777.711114444261;5811.128330750336;5847.9415205848245;5867.266591676041;4548.88627784308;4549.954500455019;4552.613027892667;4561.064981678748;4582.610478109332;4055.4525139105845;4059.411385349108;4071.3485842265686;4071.6742660587674;4086.141910198832;5395.946040539623;5584.246126772915;5593.972030139823;5642.435756424325;5682;6105.358937311607;6121.665611493517;6125.998614523584;6127.00628053041;6133.570650054543;5696.572757043238;5706.600537962303;5717.22817419643;5724.055530837365;5726.713664316768;5019.974900125549;5069.163588008011;5255.08036093682;5256.316678831728;5261.5264626183825;6119.84700382494;6153.7230824613;6157.568970172135;6161.969190154021;6166.876662466815;5943.257092863392;5980.970095149496;5991.580589358878;5991.67045812467;6003.219581454383;5979.162917191684;6033.87932241344;6157.815265542041;6216.2540495140875;6223.906641400292;5819.127130930395;5819.330776960704;5819.883602327845;5821.883562328645;5822.70886455667;5914.408559144224;5916.467517923407;5919.141724450017;5925.111233314865;5925.496332811586;5799.391063938395;5823.068309070529;5857.150713146655;5922.1412895130825;5927.703614819328;5815.5929084963645;5885.882282354243;5885.911711324248;5887.676177810262;5889.5582831286965;5820.447057529528;5826.688994976096;5829.504492118217;5830.737616807339;5868.735906884115;5618.494335509824;5664.490195882226;5850.53195744348;5899.321578018583;5902.1146827976145;5529.115341545334;5593.776248949996;5629.831105066854;5631.183478395692;5635.887282254414 04:10:23 INFO - PROCESS | 29123 | |1;ext.html;573.9770409183633;653.6960313454243;666.9633170175641;685.9931400685995;687.8933765266386;35114.29771404574;45500.54499455009;45648.77175614126;45683.314750278776;45687.31469027967;48088.27867581989;48363.75818120913;48461.27308090381;48609.27086093711;48615.75692121535;8340.040895297028;9152.039035901234;9402.388844725105;9407.2474202064;9411.482368469731;10761.892381076206;10998.285111467729;11006.779864402708;11355.1483638727;11379.374134422604;11237.3257604544;11254.943725281362;11263.26788758732;11324.32054076758;11375;11195.328280303167;11256.887431125748;11260.099192064616;11260.662180134608;11270.774584508346;10892.292001019909;10994.945025274903;11015.779684406347;11018.559257629686;11025.283356581796;44996.55003449989;45203;45295;45418.31872521915;45486.09027819425;3820.7516511426948;3849.653531182179;3857.0935830079907;3864.4589757433982;3872.8644497442738;35377.82311088428;35711;36077.278454430765;36354;36719.81640091782;4692.155412025868;4706.835260765891;4811.735354555498;4827.58965487934;4934.037862616791;384205;385719;386252;387546;389252;36970.63029369726;37939.81030094832;66735.33264667388;67166.66416667885;67211;39683;39958.800205998785;40306.395404068964;40427.39358909619;40543;15677.451289204822;15775.368985240462;15778;15804.841951580567;15815.367385304458;11861.406929653484;12521.373931303398;12806.935965320114;12882.162659427107;12973.09188356806;9162.083791620918;9167.358284920125;9167.816643667224;9206.217471514863;9267.397619154868;3527.5766907971206;3571.944686682447;3579.83890724918;3614.8192590370377;3645.0887278180453;1128.789614737019;1158.8377627132209;1195.6532605544294;1209.5101483899034;1225.289332187347;35444;35677;35966.820165899;36016.63983360133;36021.459678104846;1159.4608507044265;1174.4362705901215;1232.9815052774215;1243.5523211643801;1285.3894400160036;18492.445226643224;18594;18659.720104198448;18728.906355468134;18792.154353054146;567646;573985;574672;578114;578598;63669;63941.04088438677;64032.67983660052;64172.35827641757;64449.67775161094;1316.3352506983913;1350.4463170100212;1457.883369330451;1466.8533146685247;1469.9191544465157;32985.505217421276;35063.29873402569;37784.811075944446;37959;38028.809855950545;55684.164737529776;56556.151657724346;56655.71672141613;56724.14913776214;56770;569078;573934;575443;577136;579122;47508.52491475041;48451.5154848447;48854;49267;51270.48729512806;53005.46994530005;53145.4685453156;53752.73123634357;53917.73041134769;54065.72967135139;43843.34234986414;43989;44323.33514997213;45166.77416612896;46220;45136;46009.76995115137;46253.76873115613;47026;47134.29298560593;46040;46150.53849461597;46641.76679116583;46895.76552117217;49151.75424122856;38629.22741545097;40551.18897621972;41068.1786364277;41233.38149927813;42306.78846605747;30640.540391893694;31043.37913241768;31091.533626995162;31135.712657462525;31648.525272120474 04:10:23 INFO - PROCESS | 29123 | |2;jquery.html;393.08215317234254;429.0084862623376;434.61102313429484;441.3071477779886;444.2691772035;17048.82951170488;20690.275840345592;21280.14879404822;21325.786742132575;21774.89112554435;21159.15363385463;21159.259425920096;21195.68206476904;21196;21394.46513837151;5607.57943154263;5938.257717785277;5976.970115149418;5978.551608629348;5978.97010514949;5806.44838740319;5913.408659134074;5979.671118088503;5996.430339117798;6004.309504406983;6015.488683461889;6151.8769624607485;6152.261728592553;6154.815355539341;6156.6613836238985;5924.140999555062;6026.728797204137;6083.543734219907;6114.0828875668785;6157.1995640566665;5918.585699001072;5922.763089476416;5922.911156332659;5923.763049478059;5935.080062590306;14861.702765944729;14960.551183464513;15211;15224.314905829264;15226.619334516616;2465.9260222193184;2488.377905523619;2597.402597402589;2629.684437867449;2657.946841063168;10789.676309710721;11206.047485963703;13117.409716562777;13575.728485430334;14828.70342593153;2016.3749237736201;2054.157795303933;2055.6710926251726;2120.9257675981266;2136.134865379524;86226;89063.10936890678;91150;91594;91683;11680.123990700731;11760.470778814974;11783.587574435025;11968.82046769299;12036.277823330453;16797.32810687559;16840;16852.83147168537;16859.409920652717;16883.493495195165;8138.226868447488;8326.333893288644;8339.708110216114;8356.415050946496;8638.006629237592;7517.624118794039;7745.535267883945;7760.379169666413;7771.766846994599;8005.639746211435;5093.8726531837;5114.897702045938;5125.154349532286;5127.717975511308;5251.921221181686;2056.7737548869613;2075.772174268996;2183.661532462471;2202.702635144267;2208.4810069633622;946.8295706772847;982.1700662939804;1010.2372708604956;1088.5101704233127;1101.2566517600587;20017.399478015683;20709.792902071087;20971;21016.78983210179;21066.683999740017;1057.878343990451;1075.1506310015;1077.4881931082673;1089.8855620159768;1106.3804269609045;10988.890111098946;11582.131340149523;11603.6518904433;11610.187286889837;11662.825057624143;502100;503617;506549;513240;513938;93463;93605.19184424478;93962.53018734863;95378;99255;506.8910184310391;533.2534451767478;533.9332583427072;534.628433238903;537.8225185688714;11955.402229888297;12251.938740306241;12272.75454490923;12636.494540218288;12753.553625623059;41025.589744103374;42657.786711067485;44209.778951106324;44471.33293000672;44534.331985019606;60249;60347.39652603418;60661.393386065574;60911;63266;9132.954335228547;9340.766480838034;9447.149756521945;9486.667966621135;9490.525473726148;10828.675139745821;13460;14200.147991120153;14378.928105359824;14405.711885762017;11973.461194246105;12213.267203967791;12312.876871231172;12386.938065309616;12728.61814145577;8554.926002538268;8856;8921.241694455784;9092.318076144447;9156.221721153777;8835.425697329652;9225.400348977295;9226.400283981788;9236.907630923604;9512.667056652985;13614.387352569356;13626.863731362955;13802.65493362674;13904;14018.369173387018;8883.911160888567;13549.25479098659;13585.52450664222;13604.93197534006;13842.930785346009 04:10:23 INFO - PROCESS | 29123 | |3;mootools.html;1368.9589312320634;1536.2779493637993;1557.5249548887589;1703.4208369154492;1820.7906090799563;7954.84090318194;8724.38929274951;9017.684381046656;9018.819623607524;9225.584848681809;8936.776580585472;9012.909870901289;9013.909860901389;9017.32370072244;9017.909820901788;2497.687789026372;2536.746325367467;2537.53055684698;2537.6574162488014;2540.517301712678;2376.9286921392386;2494.451220731445;2494.825362224645;2495.238952119603;2496.8002559795264;2369.9407514812183;2380.702412198475;2412.5657381671294;2414.022320960014;2414.106780491212;2410.867402292882;2426.162973774045;2431.501542183848;2433.2578563538113;2443.230382429529;2387.904483820645;2391.426057746146;2392.2225276785116;2392.964105538418;2405.446747248143;8753.299736021103;8848.336374771918;8859.512726800023;8965.955170224172;9034;5620.494155525981;5834.2998840139035;5848.941510584925;5864.560157988125;5864.853378665525;3681.226942342111;3756.492873462102;3798.3922572388287;3876.6704830089475;3890.824912878899;1200.1778781534676;1204.0848954794333;1223.2904915149193;1244.8443944506937;1262.1165184370977;12497.687557811036;12580.937095314464;12628.24230546161;12758;12792.48830046797;4869.269609558523;4896.167651499253;4944.579710724629;4971.229459433794;5151.6136289778415;9552;9567.330286879991;9581.760455988657;9584.66453674118;9593.520323983774;5663.263775709132;5665.178549110438;5670.5747068969995;5673.971630141823;5675.716214189274;4657.74382408964;4683.836065737751;5214.504622060898;5380.91928621071;5427.6472029317965;4088.144186114851;4093.8771836844944;4198.391233271159;4208.831646734157;4229.640480559187;1179.728662407634;1191.2733232728028;1193.0574845871665;1207.5894195973235;1230.6677197156719;985.4973963278779;988.9554970026367;994.3089552760906;994.9353292035994;995.9452230127267;5868.767558812612;5870.252221095885;5883.529317654577;5930.258717660293;5985.670788106696;497.8904640978979;503.92944987701753;506.2709698034818;508.2046597075565;514.8429728932712;4088.100617864065;4181.205570941512;4208.137331846964;4308.91382172361;4320.071184695305;11427.314361138358;11809.940950295366;11902.345371004505;11936;11957.58148464817;12053.276803391824;12061;12067.818982715266;12085.335306558049;12086.395680215954;8968.865467018;8985.550722463851;9057.501837399006;9065.138811812867;9093.454392736588;5766.0971879278895;5805.419458054161;5851.356350801323;5919.171316015675;5935.643861368331;10804.891951080388;10988.560457581607;11034.448277585929;11142.164337674545;11239.887601123884;11092;11418.0865530759;11429.828552571551;11436.428178591204;11444.656660300205;6931.68807403658;7291.125064992235;7571.772846814604;7732.110807257238;7832.490888092256;8482.872756908528;8820;8823.779405514913;8837.469751814911;8838.867416988878;7761.107472640719;7779.533228006338;7920.445568810128;7940.841183176189;8026.478278911852;7752.41856860749;7890.053193616803;7913.841723165619;7949.085855126735;8083.797905052421;7125.251848555827;7261.673224705007;7271;7274.8908766367485;7278.96360518194;5384.6499977501335;5393.9730301348245;5499.450054994469;5514.641548299348;5518.751656175402;5369.409364969928;5437.728113594384;5484.670919744828;5493.890122197614;5541.750621222135 04:10:23 INFO - PROCESS | 29123 | |4;prototype.html;357.48165160517254;365.93047321009;366.75427463599385;371.76021466154316;371.85311801838276;5408.459154084594;5468.890622187554;5473.233747275384;5480.5341545968595;5495.505404513593;5024.874378140548;5147.665401748884;5335.372711323045;5347.304850369456;5385.299911011563;4253.383714188604;4256.4253825733595;4257.446531950842;4267.807948642311;4274.7862606869685;4261.850835220768;4263.5523270056665;4287.978560107211;4302.462192225972;4323.567643235667;3961.3463778476516;4040.2525532776385;4241.809118589671;4258.254805409057;4266.823919800909;4222.218889505451;4231.048014196812;4245.320748680212;4246.957530424687;4249.978750106246;3820.847166113352;3827.502424684801;3828.119532507511;3833.9424908626393;3837.9253808933545;5068.949310506885;5195.168772996302;5211.322528071364;5310.150375939869;5312.432832314476;4870.09903167915;4874.829380971649;4877.707337559722;4885.6335774817035;4909;5613.831585052414;5616.619037151028;5628.185907046477;5662.858428539278;5681.119426488859;4128.266128226152;4140.730852494578;4165.479315085614;4189.790510474494;4237.576242375769;5780.393058728815;5780.88438231233;5783.248177736909;5792.1311803229855;5802.216700745387;4305.526392096866;4311;4319.913601727948;4322.913541729148;4325.545817689161;4210.136921931058;4326.848560300375;4333.761643109596;4366.7118200131035;4377.584129507692;4830.3479030331155;4856.320115183807;4859.465458799529;4861.586765124934;4884.658073934862;5585.938671652373;5614.747336369873;5669.574781891375;5686.147077938343;5710.543156547466;1408.1269612840106;1470.6543962168882;1481.7036592681507;1507.547735679292;1516.9924150379177;949.1647350331664;1057.043375744952;1088.0207812968233;1100.4882729530646;1104.17738784606;5412.810551630752;5444.237806707064;5453.536449401766;5457.781688732485;5473.507384335429;5756.913646295309;5768.336641286306;5774.364819869895;5812.476877081083;5820.796272130455;5366.463353664602;5541.944580554224;5583.776648934078;5594.720263986785;5608.85977850557;3077.1076387847274;3090.2892334762682;3098.148009297461;3101.116181888196;3107.3629905869243;5824.650520968756;5949.405059494103;6016.548758843104;6093.878122437615;6114.899318122781;5912.7878784849;5936.8812623748145;5953.76184952597;5973.37279585646;5983.730732117066;5893.233879595625;6029.487493563009;6044.274687037496;6100.54245931548;6120.14317995481;5765.538756899522;5795.478966649521;5802.680852552981;5824.650520968756;5833.387494313206;5742.569307301886;5754.539636829128;5769.826905192851;5827.24245848054;5850.912236316543;5918.556108291809;5919.7040147991565;5926.822195334147;5982.760689572542;6055.5458340623745;4201.91596168081;4208.7895605219;4240.893977650583;4251.638610718;4264.445622069111;5801.245838041028;5851.765929362778;5854.2389489367815;5874.177615133799;5915.432410411158;5492.011437941049;5664.235328230636;5683.829485115453;5704.429557044263;5721.1990321354215;5668.489835914787;5675.843179591729;5684.744186511534;5691.26013618227;5725.885482290414;5453.945460545344;5533.00405926937;5565.916511252415;5744.913826292525;5757.919513060492;5573.080441727069;5672.205891175321;5690.829275121753;5698.948378583789;5700.263873340902;5368.8389348319615;5543.861403464784;5567.610267281251;5584.497395234448;5598.412166722598 04:10:23 INFO - PROCESS | 29123 | |5;yui.html;740.1599184925109;744.8845428958514;762.572543427021;765.2768134113262;876.3383645347761;16341.101239431826;17194.226259818322;17285.567860803454;17458.56353591161;18021;16288.918555407206;16416.261268242928;16455.09496977666;16458.423955161572;16458.506244812645;740.4853626729425;865.7446053414227;866.5060915278294;893.4326702543866;899.2850683706469;884.5135175653365;888.6845169964666;890.0609856601283;893.1068931068932;894;924.5469719837265;925.3383830561147;925.3661242049184;925.759302581327;925.9259259259243;766.6090293950069;911.6991392840347;926.7080869526111;926.9629214831399;927.2628260532892;670.1522573943964;688.4973969002656;689.2556039477353;689.6517258784282;690.0167261652176;15504.069755814688;15528.922355388264;15540;15559.377624895104;15569.455069072641;14296.356663950148;14729.263536823222;14735.557933261913;14923.552293431214;14992.325345359484;14368.928155359155;14456.49402270926;14461.349239284258;14663.060941413609;14707.779383309258;4716.292556116576;4724.574788269072;4754.358161648168;4819.855404337875;4852.393450818648;86673.56663216707;89922.5503872483;90403;91014.54492727494;91636.54181729049;12513.124081314221;12691.555795547112;12726.681832954066;12741.299228542519;12819.487220511262;24133;24384.26847194587;24389.39026524351;24437.87781061119;24544;8372.832543349099;8858.424202426822;8984.101589841095;9179.219766319937;9203.401778884485;5268.4994925482015;5280.683159010395;5324.840254792362;5347.893042139135;5469.097598896217;3810.8666196682984;3954.683625309968;3955.980220098939;3964.0486283291802;4205.222033923734;729.9744508942161;735.716749051619;740.4927624577074;748.547128986966;756.3268690865134;795.7374066558021;807.188775280839;835.3859912963967;837.2799392522426;842.8525008123554;10455.21585881062;10667.19996000303;10757.515911783988;11057.778844423066;11584.652460426201;711.3810984443567;839.694656488552;840.357126798;859.5315553023493;872.0320444306941;5090.9236361454605;5245.7114858683135;5278.155495120839;5308.672831792052;5522.337319521603;117069.41465292619;119235;119843.40078299552;123380.38309808394;123470.38264808619;74198;105633.47183264188;106655;106910.46544767382;108696;57972.13041804461;87021;88866;91132.54433727788;91806.54096729607;9760.463174525185;10151.441670708182;10305.690829275134;10411;10452.634157804441;52484;52566.21150682819;52580.211296831345;53358.46641533535;53907.191392129935;48017;48471.27293090536;73220.16949576304;76220.61889690516;78717.21282787329;12115.81826272589;12378.566750163702;12544.435500402322;12694.365281735692;13007.284599347127;13365.39855706515;13594.728105438033;13609.523666672016;14077.507287244896;14747.410103595736;13220.86779132235;13403;14147.929260353632;14656;15144.924275378993;13116.934415327862;13157.736845263233;13242.602721918358;13681.384337705027;13698.657533561342;12816.40850251527;13073.673158171121;13164.802527961896;13245;13292.734145317232;10771.353718776898;10780.730481738019;10865.565377384815;11467.942660286646;11774.94112529432;12250;12325.630231093082;12384.938075309927;12475.092442972718;12501.312427816194 04:10:23 INFO - PROCESS | 29123 | __end_tp_report 04:10:23 INFO - PROCESS | 29123 | __start_cc_report 04:10:23 INFO - PROCESS | 29123 | _x_x_mozilla_cycle_collect,1680 04:10:23 INFO - PROCESS | 29123 | __end_cc_report 04:10:23 INFO - PROCESS | 29123 | __startTimestamp1473505823865__endTimestamp 04:10:23 INFO - PROCESS | 29123 | 04:10:23 INFO - PROCESS | 29123 | ------- Summary: start ------- 04:10:23 INFO - PROCESS | 29123 | Number of tests: 6 04:10:23 INFO - PROCESS | 29123 | 04:10:23 INFO - PROCESS | 29123 | [#0] dojo.html Cycles:165 Average:5529.37 Median:5724.06 stddev:630.63 (11.0%) stddev-sans-first:605.55 04:10:23 INFO - PROCESS | 29123 | Values: 3201.5 3203.6 3231.9 3242.2 3281.8 5436.8 5518.5 5538.0 5541.9 5559.1 5488.7 5526.6 5527.9 5532.6 5558.9 4923.2 4965.3 4988.2 5032.8 5080.2 4977.5 5072.0 5074.7 5077.2 5080.5 5010.3 5037.9 5046.4 5078.3 5085.9 5127.5 5145.8 5149.0 5151.6 5175.2 4914.3 4964.8 5008.1 5062.9 5088.6 5429.3 5441.4 5447.2 5459.5 5466.2 5902.4 5915.5 5923.2 5927.4 5930.8 5682.8 5972.6 5999.5 5999.8 6032.4 5651.0 5654.3 5664.2 5671.2 5700.3 6186.6 6190.3 6191.5 6196.3 6196.8 5587.4 5833.1 5853.4 5864.4 5960.3 5985.5 5987.5 5991.3 5993.0 5993.6 5956.6 5965.2 5966.9 5967.8 5968.2 5732.9 5777.7 5811.1 5847.9 5867.3 4548.9 4550.0 4552.6 4561.1 4582.6 4055.5 4059.4 4071.3 4071.7 4086.1 5395.9 5584.2 5594.0 5642.4 5682.0 6105.4 6121.7 6126.0 6127.0 6133.6 5696.6 5706.6 5717.2 5724.1 5726.7 5020.0 5069.2 5255.1 5256.3 5261.5 6119.8 6153.7 6157.6 6162.0 6166.9 5943.3 5981.0 5991.6 5991.7 6003.2 5979.2 6033.9 6157.8 6216.3 6223.9 5819.1 5819.3 5819.9 5821.9 5822.7 5914.4 5916.5 5919.1 5925.1 5925.5 5799.4 5823.1 5857.2 5922.1 5927.7 5815.6 5885.9 5885.9 5887.7 5889.6 5820.4 5826.7 5829.5 5830.7 5868.7 5618.5 5664.5 5850.5 5899.3 5902.1 5529.1 5593.8 5629.8 5631.2 5635.9 04:10:23 INFO - PROCESS | 29123 | 04:10:23 INFO - PROCESS | 29123 | [#1] ext.html Cycles:180 Average:67687.45 Median:40132.60 stddev:138164.41 (344.3%) stddev-sans-first:138460.11 04:10:23 INFO - PROCESS | 29123 | Values: 574.0 653.7 667.0 686.0 687.9 35114.3 45500.5 45648.8 45683.3 45687.3 48088.3 48363.8 48461.3 48609.3 48615.8 8340.0 9152.0 9402.4 9407.2 9411.5 10761.9 10998.3 11006.8 11355.1 11379.4 11237.3 11254.9 11263.3 11324.3 11375.0 11195.3 11256.9 11260.1 11260.7 11270.8 10892.3 10994.9 11015.8 11018.6 11025.3 44996.6 45203.0 45295.0 45418.3 45486.1 3820.8 3849.7 3857.1 3864.5 3872.9 35377.8 35711.0 36077.3 36354.0 36719.8 4692.2 4706.8 4811.7 4827.6 4934.0 384205.0 385719.0 386252.0 387546.0 389252.0 36970.6 37939.8 66735.3 67166.7 67211.0 39683.0 39958.8 40306.4 40427.4 40543.0 15677.5 15775.4 15778.0 15804.8 15815.4 11861.4 12521.4 12806.9 12882.2 12973.1 9162.1 9167.4 9167.8 9206.2 9267.4 3527.6 3571.9 3579.8 3614.8 3645.1 1128.8 1158.8 1195.7 1209.5 1225.3 35444.0 35677.0 35966.8 36016.6 36021.5 1159.5 1174.4 1233.0 1243.6 1285.4 18492.4 18594.0 18659.7 18728.9 18792.2 567646.0 573985.0 574672.0 578114.0 578598.0 63669.0 63941.0 64032.7 64172.4 64449.7 1316.3 1350.4 1457.9 1466.9 1469.9 32985.5 35063.3 37784.8 37959.0 38028.8 55684.2 56556.2 56655.7 56724.1 56770.0 569078.0 573934.0 575443.0 577136.0 579122.0 47508.5 48451.5 48854.0 49267.0 51270.5 53005.5 53145.5 53752.7 53917.7 54065.7 43843.3 43989.0 44323.3 45166.8 46220.0 45136.0 46009.8 46253.8 47026.0 47134.3 46040.0 46150.5 46641.8 46895.8 49151.8 38629.2 40551.2 41068.2 41233.4 42306.8 30640.5 31043.4 31091.5 31135.7 31648.5 04:10:23 INFO - PROCESS | 29123 | 04:10:23 INFO - PROCESS | 29123 | [#2] jquery.html Cycles:180 Average:30028.39 Median:431.81 stddev:83895.71 (19428.9%) stddev-sans-first:84101.55 04:10:23 INFO - PROCESS | 29123 | Values: 393.1 429.0 434.6 441.3 444.3 17048.8 20690.3 21280.1 21325.8 21774.9 21159.2 21159.3 21195.7 21196.0 21394.5 5607.6 5938.3 5977.0 5978.6 5979.0 5806.4 5913.4 5979.7 5996.4 6004.3 6015.5 6151.9 6152.3 6154.8 6156.7 5924.1 6026.7 6083.5 6114.1 6157.2 5918.6 5922.8 5922.9 5923.8 5935.1 14861.7 14960.6 15211.0 15224.3 15226.6 2465.9 2488.4 2597.4 2629.7 2657.9 10789.7 11206.0 13117.4 13575.7 14828.7 2016.4 2054.2 2055.7 2120.9 2136.1 86226.0 89063.1 91150.0 91594.0 91683.0 11680.1 11760.5 11783.6 11968.8 12036.3 16797.3 16840.0 16852.8 16859.4 16883.5 8138.2 8326.3 8339.7 8356.4 8638.0 7517.6 7745.5 7760.4 7771.8 8005.6 5093.9 5114.9 5125.2 5127.7 5251.9 2056.8 2075.8 2183.7 2202.7 2208.5 946.8 982.2 1010.2 1088.5 1101.3 20017.4 20709.8 20971.0 21016.8 21066.7 1057.9 1075.2 1077.5 1089.9 1106.4 10988.9 11582.1 11603.7 11610.2 11662.8 502100.0 503617.0 506549.0 513240.0 513938.0 93463.0 93605.2 93962.5 95378.0 99255.0 506.9 533.3 533.9 534.6 537.8 11955.4 12251.9 12272.8 12636.5 12753.6 41025.6 42657.8 44209.8 44471.3 44534.3 60249.0 60347.4 60661.4 60911.0 63266.0 9133.0 9340.8 9447.1 9486.7 9490.5 10828.7 13460.0 14200.1 14378.9 14405.7 11973.5 12213.3 12312.9 12386.9 12728.6 8554.9 8856.0 8921.2 9092.3 9156.2 8835.4 9225.4 9226.4 9236.9 9512.7 13614.4 13626.9 13802.7 13904.0 14018.4 8883.9 13549.3 13585.5 13604.9 13842.9 04:10:23 INFO - PROCESS | 29123 | 04:10:23 INFO - PROCESS | 29123 | [#3] mootools.html Cycles:180 Average:6045.61 Median:2833.23 stddev:3477.80 (122.8%) stddev-sans-first:3469.80 04:10:23 INFO - PROCESS | 29123 | Values: 1369.0 1536.3 1557.5 1703.4 1820.8 7954.8 8724.4 9017.7 9018.8 9225.6 8936.8 9012.9 9013.9 9017.3 9017.9 2497.7 2536.7 2537.5 2537.7 2540.5 2376.9 2494.5 2494.8 2495.2 2496.8 2369.9 2380.7 2412.6 2414.0 2414.1 2410.9 2426.2 2431.5 2433.3 2443.2 2387.9 2391.4 2392.2 2393.0 2405.4 8753.3 8848.3 8859.5 8966.0 9034.0 5620.5 5834.3 5848.9 5864.6 5864.9 3681.2 3756.5 3798.4 3876.7 3890.8 1200.2 1204.1 1223.3 1244.8 1262.1 12497.7 12580.9 12628.2 12758.0 12792.5 4869.3 4896.2 4944.6 4971.2 5151.6 9552.0 9567.3 9581.8 9584.7 9593.5 5663.3 5665.2 5670.6 5674.0 5675.7 4657.7 4683.8 5214.5 5380.9 5427.6 4088.1 4093.9 4198.4 4208.8 4229.6 1179.7 1191.3 1193.1 1207.6 1230.7 985.5 989.0 994.3 994.9 995.9 5868.8 5870.3 5883.5 5930.3 5985.7 497.9 503.9 506.3 508.2 514.8 4088.1 4181.2 4208.1 4308.9 4320.1 11427.3 11809.9 11902.3 11936.0 11957.6 12053.3 12061.0 12067.8 12085.3 12086.4 8968.9 8985.6 9057.5 9065.1 9093.5 5766.1 5805.4 5851.4 5919.2 5935.6 10804.9 10988.6 11034.4 11142.2 11239.9 11092.0 11418.1 11429.8 11436.4 11444.7 6931.7 7291.1 7571.8 7732.1 7832.5 8482.9 8820.0 8823.8 8837.5 8838.9 7761.1 7779.5 7920.4 7940.8 8026.5 7752.4 7890.1 7913.8 7949.1 8083.8 7125.3 7261.7 7271.0 7274.9 7279.0 5384.6 5394.0 5499.5 5514.6 5518.8 5369.4 5437.7 5484.7 5493.9 5541.8 04:10:23 INFO - PROCESS | 29123 | 04:10:23 INFO - PROCESS | 29123 | [#4] prototype.html Cycles:180 Average:4802.87 Median:5455.86 stddev:1383.44 (25.4%) stddev-sans-first:1346.49 04:10:23 INFO - PROCESS | 29123 | Values: 357.5 365.9 366.8 371.8 371.9 5408.5 5468.9 5473.2 5480.5 5495.5 5024.9 5147.7 5335.4 5347.3 5385.3 4253.4 4256.4 4257.4 4267.8 4274.8 4261.9 4263.6 4288.0 4302.5 4323.6 3961.3 4040.3 4241.8 4258.3 4266.8 4222.2 4231.0 4245.3 4247.0 4250.0 3820.8 3827.5 3828.1 3833.9 3837.9 5068.9 5195.2 5211.3 5310.2 5312.4 4870.1 4874.8 4877.7 4885.6 4909.0 5613.8 5616.6 5628.2 5662.9 5681.1 4128.3 4140.7 4165.5 4189.8 4237.6 5780.4 5780.9 5783.2 5792.1 5802.2 4305.5 4311.0 4319.9 4322.9 4325.5 4210.1 4326.8 4333.8 4366.7 4377.6 4830.3 4856.3 4859.5 4861.6 4884.7 5585.9 5614.7 5669.6 5686.1 5710.5 1408.1 1470.7 1481.7 1507.5 1517.0 949.2 1057.0 1088.0 1100.5 1104.2 5412.8 5444.2 5453.5 5457.8 5473.5 5756.9 5768.3 5774.4 5812.5 5820.8 5366.5 5541.9 5583.8 5594.7 5608.9 3077.1 3090.3 3098.1 3101.1 3107.4 5824.7 5949.4 6016.5 6093.9 6114.9 5912.8 5936.9 5953.8 5973.4 5983.7 5893.2 6029.5 6044.3 6100.5 6120.1 5765.5 5795.5 5802.7 5824.7 5833.4 5742.6 5754.5 5769.8 5827.2 5850.9 5918.6 5919.7 5926.8 5982.8 6055.5 4201.9 4208.8 4240.9 4251.6 4264.4 5801.2 5851.8 5854.2 5874.2 5915.4 5492.0 5664.2 5683.8 5704.4 5721.2 5668.5 5675.8 5684.7 5691.3 5725.9 5453.9 5533.0 5565.9 5744.9 5757.9 5573.1 5672.2 5690.8 5698.9 5700.3 5368.8 5543.9 5567.6 5584.5 5598.4 04:10:23 INFO - PROCESS | 29123 | 04:10:23 INFO - PROCESS | 29123 | [#5] yui.html Cycles:180 Average:21532.45 Median:3960.01 stddev:30866.08 (779.4%) stddev-sans-first:30913.19 04:10:23 INFO - PROCESS | 29123 | Values: 740.2 744.9 762.6 765.3 876.3 16341.1 17194.2 17285.6 17458.6 18021.0 16288.9 16416.3 16455.1 16458.4 16458.5 740.5 865.7 866.5 893.4 899.3 884.5 888.7 890.1 893.1 894.0 924.5 925.3 925.4 925.8 925.9 766.6 911.7 926.7 927.0 927.3 670.2 688.5 689.3 689.7 690.0 15504.1 15528.9 15540.0 15559.4 15569.5 14296.4 14729.3 14735.6 14923.6 14992.3 14368.9 14456.5 14461.3 14663.1 14707.8 4716.3 4724.6 4754.4 4819.9 4852.4 86673.6 89922.6 90403.0 91014.5 91636.5 12513.1 12691.6 12726.7 12741.3 12819.5 24133.0 24384.3 24389.4 24437.9 24544.0 8372.8 8858.4 8984.1 9179.2 9203.4 5268.5 5280.7 5324.8 5347.9 5469.1 3810.9 3954.7 3956.0 3964.0 4205.2 730.0 735.7 740.5 748.5 756.3 795.7 807.2 835.4 837.3 842.9 10455.2 10667.2 10757.5 11057.8 11584.7 711.4 839.7 840.4 859.5 872.0 5090.9 5245.7 5278.2 5308.7 5522.3 117069.4 119235.0 119843.4 123380.4 123470.4 74198.0 105633.5 106655.0 106910.5 108696.0 57972.1 87021.0 88866.0 91132.5 91806.5 9760.5 10151.4 10305.7 10411.0 10452.6 52484.0 52566.2 52580.2 53358.5 53907.2 48017.0 48471.3 73220.2 76220.6 78717.2 12115.8 12378.6 12544.4 12694.4 13007.3 13365.4 13594.7 13609.5 14077.5 14747.4 13220.9 13403.0 14147.9 14656.0 15144.9 13116.9 13157.7 13242.6 13681.4 13698.7 12816.4 13073.7 13164.8 13245.0 13292.7 10771.4 10780.7 10865.6 11467.9 11774.9 12250.0 12325.6 12384.9 12475.1 12501.3 04:10:23 INFO - PROCESS | 29123 | -------- Summary: end -------- 04:10:23 INFO - PROCESS | 29123 | 04:10:24 INFO - TEST-INFO | 29123: exit 0 04:10:24 INFO - TEST-OK | dromaeo_css | took 1114263ms 04:10:24 INFO - TEST-START | kraken 04:10:24 INFO - operating with platform_type : linux_ 04:10:24 INFO - Initialising browser for kraken test... 04:10:24 INFO - TEST-INFO | started process 29326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa1Di2W/profile http://localhost:38238/getInfo.html) 04:10:28 INFO - PROCESS | 29326 | __metrics Screen width/height:1600/1200 04:10:28 INFO - PROCESS | 29326 | colorDepth:24 04:10:28 INFO - PROCESS | 29326 | Browser inner width/height: 1024/697 04:10:28 INFO - PROCESS | 29326 | __metrics 04:10:29 INFO - PROCESS | 29326 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:10:29 INFO - PROCESS | 29326 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:10:30 INFO - TEST-INFO | 29326: exit 0 04:10:30 INFO - Browser initialized. 04:10:30 INFO - Running cycle 1/1 for kraken test... 04:10:30 INFO - TEST-INFO | started process 29418 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa1Di2W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:10:42 INFO - PROCESS | 29418 | RSS: Main: 146022400 04:10:42 INFO - PROCESS | 29418 | 04:10:44 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38238/tests/kraken/driver.html?testName=audio-beat-detection) 04:10:45 INFO - PROCESS | 29418 | RSS: Main: 200925184 04:10:45 INFO - PROCESS | 29418 | 04:10:48 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38238/tests/kraken/driver.html?testName=audio-dft) 04:10:48 INFO - PROCESS | 29418 | RSS: Main: 185458688 04:10:48 INFO - PROCESS | 29418 | 04:10:51 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38238/tests/kraken/driver.html?testName=audio-fft) 04:10:52 INFO - PROCESS | 29418 | RSS: Main: 276389888 04:10:52 INFO - PROCESS | 29418 | 04:10:54 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38238/tests/kraken/driver.html?testName=audio-oscillator) 04:10:54 INFO - PROCESS | 29418 | RSS: Main: 182292480 04:10:54 INFO - PROCESS | 29418 | 04:10:56 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:10:57 INFO - PROCESS | 29418 | RSS: Main: 180695040 04:10:57 INFO - PROCESS | 29418 | 04:11:00 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38238/tests/kraken/driver.html?testName=imaging-darkroom) 04:11:00 INFO - PROCESS | 29418 | RSS: Main: 217194496 04:11:00 INFO - PROCESS | 29418 | 04:11:04 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38238/tests/kraken/driver.html?testName=imaging-desaturate) 04:11:04 INFO - PROCESS | 29418 | RSS: Main: 215711744 04:11:04 INFO - PROCESS | 29418 | 04:11:07 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38238/tests/kraken/driver.html?testName=json-parse-financial) 04:11:08 INFO - PROCESS | 29418 | RSS: Main: 215334912 04:11:08 INFO - PROCESS | 29418 | 04:11:09 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:11:10 INFO - PROCESS | 29418 | RSS: Main: 177418240 04:11:10 INFO - PROCESS | 29418 | 04:11:12 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38238/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:11:12 INFO - PROCESS | 29418 | RSS: Main: 184229888 04:11:12 INFO - PROCESS | 29418 | 04:11:14 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:11:15 INFO - PROCESS | 29418 | RSS: Main: 186441728 04:11:15 INFO - PROCESS | 29418 | 04:11:17 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:11:18 INFO - PROCESS | 29418 | RSS: Main: 188170240 04:11:18 INFO - PROCESS | 29418 | 04:11:21 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:11:21 INFO - PROCESS | 29418 | RSS: Main: 184676352 04:11:21 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | Cycle 1(1): loaded http://localhost:38238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38238/tests/kraken/driver.html?testName=ai-astar) 04:11:23 INFO - PROCESS | 29418 | RSS: Main: 235937792 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | __start_tp_report 04:11:23 INFO - PROCESS | 29418 | _x_x_mozilla_page_load 04:11:23 INFO - PROCESS | 29418 | _x_x_mozilla_page_load_details 04:11:23 INFO - PROCESS | 29418 | |i|pagename|runs| 04:11:23 INFO - PROCESS | 29418 | |0;ai-astar;110;110;117;109;110;109;108;108;111;110 04:11:23 INFO - PROCESS | 29418 | |1;audio-beat-detection;145;114;143;178;116;137;147;118;177;118 04:11:23 INFO - PROCESS | 29418 | |2;audio-dft;160;162;159;159;161;158;158;160;159;160 04:11:23 INFO - PROCESS | 29418 | |3;audio-fft;75;74;74;165;74;75;73;75;165;74 04:11:23 INFO - PROCESS | 29418 | |4;audio-oscillator;84;85;141;84;83;82;142;84;84;152 04:11:23 INFO - PROCESS | 29418 | |5;imaging-gaussian-blur;117;116;118;117;117;117;117;117;117;117 04:11:23 INFO - PROCESS | 29418 | |6;imaging-darkroom;146;145;146;147;145;145;145;146;145;146 04:11:23 INFO - PROCESS | 29418 | |7;imaging-desaturate;95;94;95;95;94;94;94;94;95;94 04:11:23 INFO - PROCESS | 29418 | |8;json-parse-financial;50;50;50;49;50;50;50;49;49;48 04:11:23 INFO - PROCESS | 29418 | |9;json-stringify-tinderbox;60;62;61;61;61;61;62;61;60;62 04:11:23 INFO - PROCESS | 29418 | |10;stanford-crypto-aes;75;77;72;72;76;71;72;71;73;74 04:11:23 INFO - PROCESS | 29418 | |11;stanford-crypto-ccm;126;122;125;117;131;115;117;124;166;124 04:11:23 INFO - PROCESS | 29418 | |12;stanford-crypto-pbkdf2;151;150;154;149;153;154;150;152;152;148 04:11:23 INFO - PROCESS | 29418 | |13;stanford-crypto-sha256-iterative;62;62;63;62;62;62;63;62;62;61 04:11:23 INFO - PROCESS | 29418 | __end_tp_report 04:11:23 INFO - PROCESS | 29418 | __start_cc_report 04:11:23 INFO - PROCESS | 29418 | _x_x_mozilla_cycle_collect,1716 04:11:23 INFO - PROCESS | 29418 | __end_cc_report 04:11:23 INFO - PROCESS | 29418 | __startTimestamp1473505883546__endTimestamp 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | ------- Summary: start ------- 04:11:23 INFO - PROCESS | 29418 | Number of tests: 14 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#0] ai-astar Cycles:10 Average:110.20 Median:110.00 stddev:2.57 (2.3%) stddev-sans-first:2.73 04:11:23 INFO - PROCESS | 29418 | Values: 110.0 110.0 117.0 109.0 110.0 109.0 108.0 108.0 111.0 110.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:144.00 stddev:23.85 (16.6%) stddev-sans-first:25.21 04:11:23 INFO - PROCESS | 29418 | Values: 145.0 114.0 143.0 178.0 116.0 137.0 147.0 118.0 177.0 118.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#2] audio-dft Cycles:10 Average:159.60 Median:160.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 04:11:23 INFO - PROCESS | 29418 | Values: 160.0 162.0 159.0 159.0 161.0 158.0 158.0 160.0 159.0 160.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#3] audio-fft Cycles:10 Average:92.40 Median:74.00 stddev:38.27 (51.7%) stddev-sans-first:40.07 04:11:23 INFO - PROCESS | 29418 | Values: 75.0 74.0 74.0 165.0 74.0 75.0 73.0 75.0 165.0 74.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#4] audio-oscillator Cycles:10 Average:102.10 Median:84.00 stddev:29.75 (35.4%) stddev-sans-first:30.83 04:11:23 INFO - PROCESS | 29418 | Values: 84.0 85.0 141.0 84.0 83.0 82.0 142.0 84.0 84.0 152.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 04:11:23 INFO - PROCESS | 29418 | Values: 117.0 116.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 04:11:23 INFO - PROCESS | 29418 | Values: 146.0 145.0 146.0 147.0 145.0 145.0 145.0 146.0 145.0 146.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 04:11:23 INFO - PROCESS | 29418 | Values: 95.0 94.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:50.00 stddev:0.71 (1.4%) stddev-sans-first:0.73 04:11:23 INFO - PROCESS | 29418 | Values: 50.0 50.0 50.0 49.0 50.0 50.0 50.0 49.0 49.0 48.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 04:11:23 INFO - PROCESS | 29418 | Values: 60.0 62.0 61.0 61.0 61.0 61.0 62.0 61.0 60.0 62.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.50 stddev:2.11 (2.9%) stddev-sans-first:2.15 04:11:23 INFO - PROCESS | 29418 | Values: 75.0 77.0 72.0 72.0 76.0 71.0 72.0 71.0 73.0 74.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:124.50 stddev:14.64 (11.8%) stddev-sans-first:15.52 04:11:23 INFO - PROCESS | 29418 | Values: 126.0 122.0 125.0 117.0 131.0 115.0 117.0 124.0 166.0 124.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:152.00 stddev:2.06 (1.4%) stddev-sans-first:2.18 04:11:23 INFO - PROCESS | 29418 | Values: 151.0 150.0 154.0 149.0 153.0 154.0 150.0 152.0 152.0 148.0 04:11:23 INFO - PROCESS | 29418 | 04:11:23 INFO - PROCESS | 29418 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 04:11:23 INFO - PROCESS | 29418 | Values: 62.0 62.0 63.0 62.0 62.0 62.0 63.0 62.0 62.0 61.0 04:11:23 INFO - PROCESS | 29418 | -------- Summary: end -------- 04:11:23 INFO - PROCESS | 29418 | 04:11:24 INFO - TEST-INFO | 29418: exit 0 04:11:24 INFO - TEST-OK | kraken | took 59877ms 04:11:24 INFO - SUITE-END | took 1174s 04:11:24 INFO - Completed test suite (00:19:34) 04:11:24 INFO - javascript benchmark 04:11:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3201.4717571600686, 3203.6155661320636, 3231.854566544504, 3242.157039169815, 3281.78668386555, 5436.75534600943, 5518.530924871386, 5537.972310138453, 5541.9168712469345, 5559.11054231323, 5488.725563721808, 5526.613137080406, 5527.917081243774, 5532.612717109794, 5558.916616250759, 4923.163062279403, 4965.255211718235, 4988.201887697978, 5032.798688052473, 5080.161773307418, 4977.527134922177, 5072.0, 5074.720890351029, 5077.162268225738, 5080.491950804909, 5010.323606313155, 5037.924431133536, 5046.394432668067, 5078.339815823938, 5085.949140508603, 5127.48725127488, 5145.794168233266, 5149.021685879671, 5151.587872970191, 5175.24958880962, 4914.311996320518, 4964.776585053645, 5008.098542262392, 5062.898742025176, 5088.567471764904, 5429.321334833146, 5441.374241962186, 5447.210154527611, 5459.454054594548, 5466.207399927028, 5902.439268269551, 5915.467607915265, 5923.17075609415, 5927.407259274038, 5930.7924222652, 5682.801101961411, 5972.581919265654, 5999.460048595603, 5999.790007349722, 6032.426919442646, 5651.011073062239, 5654.321481422174, 5664.20701101846, 5671.234383358235, 5700.344460387026, 6186.566940314225, 6190.288116866618, 6191.504679625619, 6196.349383314777, 6196.845078872974, 5587.44125587438, 5833.095870140135, 5853.356130825606, 5864.44287792659, 5960.254968128984, 5985.521158307325, 5987.520998320124, 5991.340952495222, 5992.970035149883, 5993.580449368589, 5956.583039187302, 5965.224520812267, 5966.910496342558, 5967.761289548456, 5968.1942937703725, 5732.942670573324, 5777.711114444261, 5811.128330750336, 5847.9415205848245, 5867.266591676041, 4548.88627784308, 4549.954500455019, 4552.613027892667, 4561.064981678748, 4582.610478109332, 4055.4525139105845, 4059.411385349108, 4071.3485842265686, 4071.6742660587674, 4086.141910198832, 5395.946040539623, 5584.246126772915, 5593.972030139823, 5642.435756424325, 5682.0, 6105.358937311607, 6121.665611493517, 6125.998614523584, 6127.00628053041, 6133.570650054543, 5696.572757043238, 5706.600537962303, 5717.22817419643, 5724.055530837365, 5726.713664316768, 5019.974900125549, 5069.163588008011, 5255.08036093682, 5256.316678831728, 5261.5264626183825, 6119.84700382494, 6153.7230824613, 6157.568970172135, 6161.969190154021, 6166.876662466815, 5943.257092863392, 5980.970095149496, 5991.580589358878, 5991.67045812467, 6003.219581454383, 5979.162917191684, 6033.87932241344, 6157.815265542041, 6216.2540495140875, 6223.906641400292, 5819.127130930395, 5819.330776960704, 5819.883602327845, 5821.883562328645, 5822.70886455667, 5914.408559144224, 5916.467517923407, 5919.141724450017, 5925.111233314865, 5925.496332811586, 5799.391063938395, 5823.068309070529, 5857.150713146655, 5922.1412895130825, 5927.703614819328, 5815.5929084963645, 5885.882282354243, 5885.911711324248, 5887.676177810262, 5889.5582831286965, 5820.447057529528, 5826.688994976096, 5829.504492118217, 5830.737616807339, 5868.735906884115, 5618.494335509824, 5664.490195882226, 5850.53195744348, 5899.321578018583, 5902.1146827976145, 5529.115341545334, 5593.776248949996, 5629.831105066854, 5631.183478395692, 5635.887282254414], "value": 5486.831225408019, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.9770409183633, 653.6960313454243, 666.9633170175641, 685.9931400685995, 687.8933765266386, 35114.29771404574, 45500.54499455009, 45648.77175614126, 45683.314750278776, 45687.31469027967, 48088.27867581989, 48363.75818120913, 48461.27308090381, 48609.27086093711, 48615.75692121535, 8340.040895297028, 9152.039035901234, 9402.388844725105, 9407.2474202064, 9411.482368469731, 10761.892381076206, 10998.285111467729, 11006.779864402708, 11355.1483638727, 11379.374134422604, 11237.3257604544, 11254.943725281362, 11263.26788758732, 11324.32054076758, 11375.0, 11195.328280303167, 11256.887431125748, 11260.099192064616, 11260.662180134608, 11270.774584508346, 10892.292001019909, 10994.945025274903, 11015.779684406347, 11018.559257629686, 11025.283356581796, 44996.55003449989, 45203.0, 45295.0, 45418.31872521915, 45486.09027819425, 3820.7516511426948, 3849.653531182179, 3857.0935830079907, 3864.4589757433982, 3872.8644497442738, 35377.82311088428, 35711.0, 36077.278454430765, 36354.0, 36719.81640091782, 4692.155412025868, 4706.835260765891, 4811.735354555498, 4827.58965487934, 4934.037862616791, 384205.0, 385719.0, 386252.0, 387546.0, 389252.0, 36970.63029369726, 37939.81030094832, 66735.33264667388, 67166.66416667885, 67211.0, 39683.0, 39958.800205998785, 40306.395404068964, 40427.39358909619, 40543.0, 15677.451289204822, 15775.368985240462, 15778.0, 15804.841951580567, 15815.367385304458, 11861.406929653484, 12521.373931303398, 12806.935965320114, 12882.162659427107, 12973.09188356806, 9162.083791620918, 9167.358284920125, 9167.816643667224, 9206.217471514863, 9267.397619154868, 3527.5766907971206, 3571.944686682447, 3579.83890724918, 3614.8192590370377, 3645.0887278180453, 1128.789614737019, 1158.8377627132209, 1195.6532605544294, 1209.5101483899034, 1225.289332187347, 35444.0, 35677.0, 35966.820165899, 36016.63983360133, 36021.459678104846, 1159.4608507044265, 1174.4362705901215, 1232.9815052774215, 1243.5523211643801, 1285.3894400160036, 18492.445226643224, 18594.0, 18659.720104198448, 18728.906355468134, 18792.154353054146, 567646.0, 573985.0, 574672.0, 578114.0, 578598.0, 63669.0, 63941.04088438677, 64032.67983660052, 64172.35827641757, 64449.67775161094, 1316.3352506983913, 1350.4463170100212, 1457.883369330451, 1466.8533146685247, 1469.9191544465157, 32985.505217421276, 35063.29873402569, 37784.811075944446, 37959.0, 38028.809855950545, 55684.164737529776, 56556.151657724346, 56655.71672141613, 56724.14913776214, 56770.0, 569078.0, 573934.0, 575443.0, 577136.0, 579122.0, 47508.52491475041, 48451.5154848447, 48854.0, 49267.0, 51270.48729512806, 53005.46994530005, 53145.4685453156, 53752.73123634357, 53917.73041134769, 54065.72967135139, 43843.34234986414, 43989.0, 44323.33514997213, 45166.77416612896, 46220.0, 45136.0, 46009.76995115137, 46253.76873115613, 47026.0, 47134.29298560593, 46040.0, 46150.53849461597, 46641.76679116583, 46895.76552117217, 49151.75424122856, 38629.22741545097, 40551.18897621972, 41068.1786364277, 41233.38149927813, 42306.78846605747, 30640.540391893694, 31043.37913241768, 31091.533626995162, 31135.712657462525, 31648.525272120474], "value": 21277.07817528613, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.08215317234254, 429.0084862623376, 434.61102313429484, 441.3071477779886, 444.2691772035, 17048.82951170488, 20690.275840345592, 21280.14879404822, 21325.786742132575, 21774.89112554435, 21159.15363385463, 21159.259425920096, 21195.68206476904, 21196.0, 21394.46513837151, 5607.57943154263, 5938.257717785277, 5976.970115149418, 5978.551608629348, 5978.97010514949, 5806.44838740319, 5913.408659134074, 5979.671118088503, 5996.430339117798, 6004.309504406983, 6015.488683461889, 6151.8769624607485, 6152.261728592553, 6154.815355539341, 6156.6613836238985, 5924.140999555062, 6026.728797204137, 6083.543734219907, 6114.0828875668785, 6157.1995640566665, 5918.585699001072, 5922.763089476416, 5922.911156332659, 5923.763049478059, 5935.080062590306, 14861.702765944729, 14960.551183464513, 15211.0, 15224.314905829264, 15226.619334516616, 2465.9260222193184, 2488.377905523619, 2597.402597402589, 2629.684437867449, 2657.946841063168, 10789.676309710721, 11206.047485963703, 13117.409716562777, 13575.728485430334, 14828.70342593153, 2016.3749237736201, 2054.157795303933, 2055.6710926251726, 2120.9257675981266, 2136.134865379524, 86226.0, 89063.10936890678, 91150.0, 91594.0, 91683.0, 11680.123990700731, 11760.470778814974, 11783.587574435025, 11968.82046769299, 12036.277823330453, 16797.32810687559, 16840.0, 16852.83147168537, 16859.409920652717, 16883.493495195165, 8138.226868447488, 8326.333893288644, 8339.708110216114, 8356.415050946496, 8638.006629237592, 7517.624118794039, 7745.535267883945, 7760.379169666413, 7771.766846994599, 8005.639746211435, 5093.8726531837, 5114.897702045938, 5125.154349532286, 5127.717975511308, 5251.921221181686, 2056.7737548869613, 2075.772174268996, 2183.661532462471, 2202.702635144267, 2208.4810069633622, 946.8295706772847, 982.1700662939804, 1010.2372708604956, 1088.5101704233127, 1101.2566517600587, 20017.399478015683, 20709.792902071087, 20971.0, 21016.78983210179, 21066.683999740017, 1057.878343990451, 1075.1506310015, 1077.4881931082673, 1089.8855620159768, 1106.3804269609045, 10988.890111098946, 11582.131340149523, 11603.6518904433, 11610.187286889837, 11662.825057624143, 502100.0, 503617.0, 506549.0, 513240.0, 513938.0, 93463.0, 93605.19184424478, 93962.53018734863, 95378.0, 99255.0, 506.8910184310391, 533.2534451767478, 533.9332583427072, 534.628433238903, 537.8225185688714, 11955.402229888297, 12251.938740306241, 12272.75454490923, 12636.494540218288, 12753.553625623059, 41025.589744103374, 42657.786711067485, 44209.778951106324, 44471.33293000672, 44534.331985019606, 60249.0, 60347.39652603418, 60661.393386065574, 60911.0, 63266.0, 9132.954335228547, 9340.766480838034, 9447.149756521945, 9486.667966621135, 9490.525473726148, 10828.675139745821, 13460.0, 14200.147991120153, 14378.928105359824, 14405.711885762017, 11973.461194246105, 12213.267203967791, 12312.876871231172, 12386.938065309616, 12728.61814145577, 8554.926002538268, 8856.0, 8921.241694455784, 9092.318076144447, 9156.221721153777, 8835.425697329652, 9225.400348977295, 9226.400283981788, 9236.907630923604, 9512.667056652985, 13614.387352569356, 13626.863731362955, 13802.65493362674, 13904.0, 14018.369173387018, 8883.911160888567, 13549.25479098659, 13585.52450664222, 13604.93197534006, 13842.930785346009], "value": 9363.75366467668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.9589312320634, 1536.2779493637993, 1557.5249548887589, 1703.4208369154492, 1820.7906090799563, 7954.84090318194, 8724.38929274951, 9017.684381046656, 9018.819623607524, 9225.584848681809, 8936.776580585472, 9012.909870901289, 9013.909860901389, 9017.32370072244, 9017.909820901788, 2497.687789026372, 2536.746325367467, 2537.53055684698, 2537.6574162488014, 2540.517301712678, 2376.9286921392386, 2494.451220731445, 2494.825362224645, 2495.238952119603, 2496.8002559795264, 2369.9407514812183, 2380.702412198475, 2412.5657381671294, 2414.022320960014, 2414.106780491212, 2410.867402292882, 2426.162973774045, 2431.501542183848, 2433.2578563538113, 2443.230382429529, 2387.904483820645, 2391.426057746146, 2392.2225276785116, 2392.964105538418, 2405.446747248143, 8753.299736021103, 8848.336374771918, 8859.512726800023, 8965.955170224172, 9034.0, 5620.494155525981, 5834.2998840139035, 5848.941510584925, 5864.560157988125, 5864.853378665525, 3681.226942342111, 3756.492873462102, 3798.3922572388287, 3876.6704830089475, 3890.824912878899, 1200.1778781534676, 1204.0848954794333, 1223.2904915149193, 1244.8443944506937, 1262.1165184370977, 12497.687557811036, 12580.937095314464, 12628.24230546161, 12758.0, 12792.48830046797, 4869.269609558523, 4896.167651499253, 4944.579710724629, 4971.229459433794, 5151.6136289778415, 9552.0, 9567.330286879991, 9581.760455988657, 9584.66453674118, 9593.520323983774, 5663.263775709132, 5665.178549110438, 5670.5747068969995, 5673.971630141823, 5675.716214189274, 4657.74382408964, 4683.836065737751, 5214.504622060898, 5380.91928621071, 5427.6472029317965, 4088.144186114851, 4093.8771836844944, 4198.391233271159, 4208.831646734157, 4229.640480559187, 1179.728662407634, 1191.2733232728028, 1193.0574845871665, 1207.5894195973235, 1230.6677197156719, 985.4973963278779, 988.9554970026367, 994.3089552760906, 994.9353292035994, 995.9452230127267, 5868.767558812612, 5870.252221095885, 5883.529317654577, 5930.258717660293, 5985.670788106696, 497.8904640978979, 503.92944987701753, 506.2709698034818, 508.2046597075565, 514.8429728932712, 4088.100617864065, 4181.205570941512, 4208.137331846964, 4308.91382172361, 4320.071184695305, 11427.314361138358, 11809.940950295366, 11902.345371004505, 11936.0, 11957.58148464817, 12053.276803391824, 12061.0, 12067.818982715266, 12085.335306558049, 12086.395680215954, 8968.865467018, 8985.550722463851, 9057.501837399006, 9065.138811812867, 9093.454392736588, 5766.0971879278895, 5805.419458054161, 5851.356350801323, 5919.171316015675, 5935.643861368331, 10804.891951080388, 10988.560457581607, 11034.448277585929, 11142.164337674545, 11239.887601123884, 11092.0, 11418.0865530759, 11429.828552571551, 11436.428178591204, 11444.656660300205, 6931.68807403658, 7291.125064992235, 7571.772846814604, 7732.110807257238, 7832.490888092256, 8482.872756908528, 8820.0, 8823.779405514913, 8837.469751814911, 8838.867416988878, 7761.107472640719, 7779.533228006338, 7920.445568810128, 7940.841183176189, 8026.478278911852, 7752.41856860749, 7890.053193616803, 7913.841723165619, 7949.085855126735, 8083.797905052421, 7125.251848555827, 7261.673224705007, 7271.0, 7274.8908766367485, 7278.96360518194, 5384.6499977501335, 5393.9730301348245, 5499.450054994469, 5514.641548299348, 5518.751656175402, 5369.409364969928, 5437.728113594384, 5484.670919744828, 5493.890122197614, 5541.750621222135], "value": 4756.444973389783, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.48165160517254, 365.93047321009, 366.75427463599385, 371.76021466154316, 371.85311801838276, 5408.459154084594, 5468.890622187554, 5473.233747275384, 5480.5341545968595, 5495.505404513593, 5024.874378140548, 5147.665401748884, 5335.372711323045, 5347.304850369456, 5385.299911011563, 4253.383714188604, 4256.4253825733595, 4257.446531950842, 4267.807948642311, 4274.7862606869685, 4261.850835220768, 4263.5523270056665, 4287.978560107211, 4302.462192225972, 4323.567643235667, 3961.3463778476516, 4040.2525532776385, 4241.809118589671, 4258.254805409057, 4266.823919800909, 4222.218889505451, 4231.048014196812, 4245.320748680212, 4246.957530424687, 4249.978750106246, 3820.847166113352, 3827.502424684801, 3828.119532507511, 3833.9424908626393, 3837.9253808933545, 5068.949310506885, 5195.168772996302, 5211.322528071364, 5310.150375939869, 5312.432832314476, 4870.09903167915, 4874.829380971649, 4877.707337559722, 4885.6335774817035, 4909.0, 5613.831585052414, 5616.619037151028, 5628.185907046477, 5662.858428539278, 5681.119426488859, 4128.266128226152, 4140.730852494578, 4165.479315085614, 4189.790510474494, 4237.576242375769, 5780.393058728815, 5780.88438231233, 5783.248177736909, 5792.1311803229855, 5802.216700745387, 4305.526392096866, 4311.0, 4319.913601727948, 4322.913541729148, 4325.545817689161, 4210.136921931058, 4326.848560300375, 4333.761643109596, 4366.7118200131035, 4377.584129507692, 4830.3479030331155, 4856.320115183807, 4859.465458799529, 4861.586765124934, 4884.658073934862, 5585.938671652373, 5614.747336369873, 5669.574781891375, 5686.147077938343, 5710.543156547466, 1408.1269612840106, 1470.6543962168882, 1481.7036592681507, 1507.547735679292, 1516.9924150379177, 949.1647350331664, 1057.043375744952, 1088.0207812968233, 1100.4882729530646, 1104.17738784606, 5412.810551630752, 5444.237806707064, 5453.536449401766, 5457.781688732485, 5473.507384335429, 5756.913646295309, 5768.336641286306, 5774.364819869895, 5812.476877081083, 5820.796272130455, 5366.463353664602, 5541.944580554224, 5583.776648934078, 5594.720263986785, 5608.85977850557, 3077.1076387847274, 3090.2892334762682, 3098.148009297461, 3101.116181888196, 3107.3629905869243, 5824.650520968756, 5949.405059494103, 6016.548758843104, 6093.878122437615, 6114.899318122781, 5912.7878784849, 5936.8812623748145, 5953.76184952597, 5973.37279585646, 5983.730732117066, 5893.233879595625, 6029.487493563009, 6044.274687037496, 6100.54245931548, 6120.14317995481, 5765.538756899522, 5795.478966649521, 5802.680852552981, 5824.650520968756, 5833.387494313206, 5742.569307301886, 5754.539636829128, 5769.826905192851, 5827.24245848054, 5850.912236316543, 5918.556108291809, 5919.7040147991565, 5926.822195334147, 5982.760689572542, 6055.5458340623745, 4201.91596168081, 4208.7895605219, 4240.893977650583, 4251.638610718, 4264.445622069111, 5801.245838041028, 5851.765929362778, 5854.2389489367815, 5874.177615133799, 5915.432410411158, 5492.011437941049, 5664.235328230636, 5683.829485115453, 5704.429557044263, 5721.1990321354215, 5668.489835914787, 5675.843179591729, 5684.744186511534, 5691.26013618227, 5725.885482290414, 5453.945460545344, 5533.00405926937, 5565.916511252415, 5744.913826292525, 5757.919513060492, 5573.080441727069, 5672.205891175321, 5690.829275121753, 5698.948378583789, 5700.263873340902, 5368.8389348319615, 5543.861403464784, 5567.610267281251, 5584.497395234448, 5598.412166722598], "value": 4374.709554767122, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.1599184925109, 744.8845428958514, 762.572543427021, 765.2768134113262, 876.3383645347761, 16341.101239431826, 17194.226259818322, 17285.567860803454, 17458.56353591161, 18021.0, 16288.918555407206, 16416.261268242928, 16455.09496977666, 16458.423955161572, 16458.506244812645, 740.4853626729425, 865.7446053414227, 866.5060915278294, 893.4326702543866, 899.2850683706469, 884.5135175653365, 888.6845169964666, 890.0609856601283, 893.1068931068932, 894.0, 924.5469719837265, 925.3383830561147, 925.3661242049184, 925.759302581327, 925.9259259259243, 766.6090293950069, 911.6991392840347, 926.7080869526111, 926.9629214831399, 927.2628260532892, 670.1522573943964, 688.4973969002656, 689.2556039477353, 689.6517258784282, 690.0167261652176, 15504.069755814688, 15528.922355388264, 15540.0, 15559.377624895104, 15569.455069072641, 14296.356663950148, 14729.263536823222, 14735.557933261913, 14923.552293431214, 14992.325345359484, 14368.928155359155, 14456.49402270926, 14461.349239284258, 14663.060941413609, 14707.779383309258, 4716.292556116576, 4724.574788269072, 4754.358161648168, 4819.855404337875, 4852.393450818648, 86673.56663216707, 89922.5503872483, 90403.0, 91014.54492727494, 91636.54181729049, 12513.124081314221, 12691.555795547112, 12726.681832954066, 12741.299228542519, 12819.487220511262, 24133.0, 24384.26847194587, 24389.39026524351, 24437.87781061119, 24544.0, 8372.832543349099, 8858.424202426822, 8984.101589841095, 9179.219766319937, 9203.401778884485, 5268.4994925482015, 5280.683159010395, 5324.840254792362, 5347.893042139135, 5469.097598896217, 3810.8666196682984, 3954.683625309968, 3955.980220098939, 3964.0486283291802, 4205.222033923734, 729.9744508942161, 735.716749051619, 740.4927624577074, 748.547128986966, 756.3268690865134, 795.7374066558021, 807.188775280839, 835.3859912963967, 837.2799392522426, 842.8525008123554, 10455.21585881062, 10667.19996000303, 10757.515911783988, 11057.778844423066, 11584.652460426201, 711.3810984443567, 839.694656488552, 840.357126798, 859.5315553023493, 872.0320444306941, 5090.9236361454605, 5245.7114858683135, 5278.155495120839, 5308.672831792052, 5522.337319521603, 117069.41465292619, 119235.0, 119843.40078299552, 123380.38309808394, 123470.38264808619, 74198.0, 105633.47183264188, 106655.0, 106910.46544767382, 108696.0, 57972.13041804461, 87021.0, 88866.0, 91132.54433727788, 91806.54096729607, 9760.463174525185, 10151.441670708182, 10305.690829275134, 10411.0, 10452.634157804441, 52484.0, 52566.21150682819, 52580.211296831345, 53358.46641533535, 53907.191392129935, 48017.0, 48471.27293090536, 73220.16949576304, 76220.61889690516, 78717.21282787329, 12115.81826272589, 12378.566750163702, 12544.435500402322, 12694.365281735692, 13007.284599347127, 13365.39855706515, 13594.728105438033, 13609.523666672016, 14077.507287244896, 14747.410103595736, 13220.86779132235, 13403.0, 14147.929260353632, 14656.0, 15144.924275378993, 13116.934415327862, 13157.736845263233, 13242.602721918358, 13681.384337705027, 13698.657533561342, 12816.40850251527, 13073.673158171121, 13164.802527961896, 13245.0, 13292.734145317232, 10771.353718776898, 10780.730481738019, 10865.565377384815, 11467.942660286646, 11774.94112529432, 12250.0, 12325.630231093082, 12384.938075309927, 12475.092442972718, 12501.312427816194], "value": 8092.536023387545, "unit": "score"}], "name": "dromaeo_css", "value": 7542.363075790916, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 117.0, 109.0, 110.0, 109.0, 108.0, 108.0, 111.0, 110.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 143.0, 178.0, 116.0, 137.0, 147.0, 118.0, 177.0, 118.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 159.0, 159.0, 161.0, 158.0, 158.0, 160.0, 159.0, 160.0], "value": 159.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 165.0, 74.0, 75.0, 73.0, 75.0, 165.0, 74.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 141.0, 84.0, 83.0, 82.0, 142.0, 84.0, 84.0, 152.0], "value": 102.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 147.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 49.0, 50.0, 50.0, 50.0, 49.0, 49.0, 48.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 61.0, 61.0, 61.0, 61.0, 62.0, 61.0, 60.0, 62.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 72.0, 72.0, 76.0, 71.0, 72.0, 71.0, 73.0, 74.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 122.0, 125.0, 117.0, 131.0, 115.0, 117.0, 124.0, 166.0, 124.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 154.0, 149.0, 153.0, 154.0, 150.0, 152.0, 152.0, 148.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 62.0, 62.0, 63.0, 62.0, 62.0, 61.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1484.6, "alertThreshold": 2.0}]} 04:11:24 INFO - Return code: 0 04:11:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:11:24 INFO - # TBPL SUCCESS # 04:11:24 INFO - Running post-action listener: _resource_record_post_action 04:11:24 INFO - [mozharness: 2016-09-10 11:11:24.857146Z] Finished run-tests step (success) 04:11:24 INFO - Running post-run listener: _resource_record_post_run 04:11:24 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 266240; Write bytes: 328740864; Read time: 28; Write time: 241464 04:11:24 INFO - TinderboxPrint: CPU usage
12.9% 04:11:24 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 04:11:24 INFO - TinderboxPrint: I/O write bytes / time
328,740,864 / 241,464 04:11:24 INFO - TinderboxPrint: CPU idle
8,277.2 (87.1%) 04:11:24 INFO - TinderboxPrint: CPU user
1,184.9 (12.5%) 04:11:24 INFO - TinderboxPrint: Swap in / out
0 / 0 04:11:24 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78508032; Read time: 0; Write time: 83052 04:11:24 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249794560; Read time: 16; Write time: 158372 04:11:25 INFO - Running post-run listener: _upload_blobber_files 04:11:25 INFO - Blob upload gear active. 04:11:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:11:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:11:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:11:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:11:25 INFO - (blobuploader) - INFO - Open directory for files ... 04:11:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:11:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:11:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:11:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:29 INFO - (blobuploader) - INFO - Done attempting. 04:11:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:11:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:11:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:11:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:29 INFO - (blobuploader) - INFO - Done attempting. 04:11:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:11:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:11:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:11:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:30 INFO - (blobuploader) - INFO - Done attempting. 04:11:30 INFO - (blobuploader) - INFO - Iteration through files over. 04:11:30 INFO - Return code: 0 04:11:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:11:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:11:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f3d49810e47e730e0f1dc2f496b7fff97aa43ea6252da2d841a6fd8f183f8980898774e85d640be1b5c86afa21dc830d8f4ebc7045e5d8cc7560e75b64be024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff6d85cd489cafabe6059590e54ee3b0663ed394cb80df3d44adaf21762be3551e2c8eee7b92fb501a450f7c4469bf47657b561c14b2530f1163a4bd91d7e9e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee909a7174811e7539d73761fa074977fa0d5734cbd1b2f3e95d9c17dda8ca21a0a4a9ca2a05bbf66baaa63fb2cf30398b4226471feac1d62f86008b43d13d14"} 04:11:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:11:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:11:30 INFO - Contents: 04:11:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f3d49810e47e730e0f1dc2f496b7fff97aa43ea6252da2d841a6fd8f183f8980898774e85d640be1b5c86afa21dc830d8f4ebc7045e5d8cc7560e75b64be024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff6d85cd489cafabe6059590e54ee3b0663ed394cb80df3d44adaf21762be3551e2c8eee7b92fb501a450f7c4469bf47657b561c14b2530f1163a4bd91d7e9e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee909a7174811e7539d73761fa074977fa0d5734cbd1b2f3e95d9c17dda8ca21a0a4a9ca2a05bbf66baaa63fb2cf30398b4226471feac1d62f86008b43d13d14"} 04:11:30 INFO - Running post-run listener: copy_logs_to_upload_dir 04:11:30 INFO - Copying logs to upload dir... 04:11:30 INFO - mkdir: /builds/slave/test/build/upload/logs 04:11:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.474091 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-10 04:11:30.025734) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 04:11:30.032365) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1473504100.924780-978638062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f3d49810e47e730e0f1dc2f496b7fff97aa43ea6252da2d841a6fd8f183f8980898774e85d640be1b5c86afa21dc830d8f4ebc7045e5d8cc7560e75b64be024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff6d85cd489cafabe6059590e54ee3b0663ed394cb80df3d44adaf21762be3551e2c8eee7b92fb501a450f7c4469bf47657b561c14b2530f1163a4bd91d7e9e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee909a7174811e7539d73761fa074977fa0d5734cbd1b2f3e95d9c17dda8ca21a0a4a9ca2a05bbf66baaa63fb2cf30398b4226471feac1d62f86008b43d13d14"} build_url:https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011474 build_url: 'https://queue.taskcluster.net/v1/task/UjuVstA-RjelzXaqQS2AcA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f3d49810e47e730e0f1dc2f496b7fff97aa43ea6252da2d841a6fd8f183f8980898774e85d640be1b5c86afa21dc830d8f4ebc7045e5d8cc7560e75b64be024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ff6d85cd489cafabe6059590e54ee3b0663ed394cb80df3d44adaf21762be3551e2c8eee7b92fb501a450f7c4469bf47657b561c14b2530f1163a4bd91d7e9e3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee909a7174811e7539d73761fa074977fa0d5734cbd1b2f3e95d9c17dda8ca21a0a4a9ca2a05bbf66baaa63fb2cf30398b4226471feac1d62f86008b43d13d14"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 04:11:30.068901) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 04:11:30.069266) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1473504100.924780-978638062 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 04:11:30.121753) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 04:11:30.122081) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 04:11:30.122418) ========= ========= Total master_lag: 0.15 =========