builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1471518328.65 results: success (0) buildid: 20160817151514 builduid: 5ca560887ea949039c2417d51dd6fc7c revision: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.653839) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.654407) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.654695) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.824723) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.824987) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 04:05:29-- 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% 37.8M=0s 2016-08-18 04:05:29 (37.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133302 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.995137) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.995405) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018655 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.061880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.062213) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8ececbd9880dc48a879d7a0d44dbf5e15248b09c --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 8ececbd9880dc48a879d7a0d44dbf5e15248b09c --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=edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 04:05:29,282 truncating revision to first 12 chars 2016-08-18 04:05:29,282 Setting DEBUG logging. 2016-08-18 04:05:29,282 attempt 1/10 2016-08-18 04:05:29,282 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8ececbd9880d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 04:05:29,664 unpacking tar archive at: mozilla-aurora-8ececbd9880d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.729244 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.812717) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.816739) ========= script_repo_revision: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.817126) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.817395) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.828517) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 13 secs) (at 2016-08-18 04:05:29.828826) ========= /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=edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:05:30 INFO - MultiFileLogger online at 20160818 04:05:30 in /builds/slave/test 04:05:30 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 04:05:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:05:30 INFO - {'append_to_log': False, 04:05:30 INFO - 'base_work_dir': '/builds/slave/test', 04:05:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:05:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:05:30 INFO - 'branch': 'Mozilla-Aurora', 04:05:30 INFO - 'buildbot_json_path': 'buildprops.json', 04:05:30 INFO - 'config_files': ('talos/linux_config.py',), 04:05:30 INFO - 'default_actions': ('clobber', 04:05:30 INFO - 'read-buildbot-config', 04:05:30 INFO - 'download-and-extract', 04:05:30 INFO - 'populate-webroot', 04:05:30 INFO - 'create-virtualenv', 04:05:30 INFO - 'install', 04:05:30 INFO - 'run-tests'), 04:05:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:05:30 INFO - 'download_minidump_stackwalk': True, 04:05:30 INFO - 'download_symbols': 'ondemand', 04:05:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:05:30 INFO - 'tooltool.py': '/tools/tooltool.py', 04:05:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:05:30 INFO - '/tools/misc-python/virtualenv.py')}, 04:05:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:05:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:05:30 INFO - 'installer_path': 'installer.exe', 04:05:30 INFO - 'log_level': 'info', 04:05:30 INFO - 'log_name': 'talos', 04:05:30 INFO - 'log_to_console': True, 04:05:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:05:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:05:30 INFO - 'opt_config_files': (), 04:05:30 INFO - 'pip_index': False, 04:05:30 INFO - 'sps_profile': False, 04:05:30 INFO - 'sps_profile_interval': 0, 04:05:30 INFO - 'suite': 'dromaeojs', 04:05:30 INFO - 'system_bits': '32', 04:05:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:05:30 INFO - 'title': 'talos-linux64-ix-033', 04:05:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:05:30 INFO - 'use_talos_json': True, 04:05:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:05:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:05:30 INFO - 'work_dir': 'build'} 04:05:30 INFO - [mozharness: 2016-08-18 11:05:30.134760Z] Running clobber step. 04:05:30 INFO - Running pre-action listener: _resource_record_pre_action 04:05:30 INFO - Running main action method: clobber 04:05:30 INFO - rmtree: /builds/slave/test/build 04:05:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:05:30 INFO - Running post-action listener: _resource_record_post_action 04:05:30 INFO - [mozharness: 2016-08-18 11:05:30.598485Z] Finished clobber step (success) 04:05:30 INFO - [mozharness: 2016-08-18 11:05:30.598586Z] Running read-buildbot-config step. 04:05:30 INFO - Running pre-action listener: _resource_record_pre_action 04:05:30 INFO - Running main action method: read_buildbot_config 04:05:30 INFO - Using buildbot properties: 04:05:30 INFO - { 04:05:30 INFO - "project": "", 04:05:30 INFO - "product": "firefox", 04:05:30 INFO - "script_repo_revision": "production", 04:05:30 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:05:30 INFO - "repository": "", 04:05:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:05:30 INFO - "buildid": "20160817151514", 04:05:30 INFO - "pgo_build": "True", 04:05:30 INFO - "basedir": "/builds/slave/test", 04:05:30 INFO - "buildnumber": 685, 04:05:30 INFO - "slavename": "talos-linux64-ix-033", 04:05:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:05:30 INFO - "platform": "ubuntu64_hw", 04:05:30 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:05:30 INFO - "branch": "mozilla-aurora", 04:05:30 INFO - "repo_path": "releases/mozilla-aurora", 04:05:30 INFO - "revision": "8ececbd9880dc48a879d7a0d44dbf5e15248b09c", 04:05:30 INFO - "stage_platform": "linux64-pgo", 04:05:30 INFO - "builduid": "5ca560887ea949039c2417d51dd6fc7c", 04:05:30 INFO - "slavebuilddir": "test" 04:05:30 INFO - } 04:05:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 04:05:30 INFO - Running post-action listener: _resource_record_post_action 04:05:30 INFO - [mozharness: 2016-08-18 11:05:30.601018Z] Finished read-buildbot-config step (success) 04:05:30 INFO - [mozharness: 2016-08-18 11:05:30.601107Z] Running download-and-extract step. 04:05:30 INFO - Running pre-action listener: _resource_record_pre_action 04:05:30 INFO - Running main action method: download_and_extract 04:05:30 INFO - mkdir: /builds/slave/test/build/tests 04:05:30 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')]} 04:05:30 INFO - https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:05:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:05:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:05:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:05:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:05:30 INFO - retry: Failed, sleeping 30 seconds before retrying 04:06:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:06:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:06:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:06:00 INFO - retry: Failed, sleeping 60 seconds before retrying 04:07:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:07:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:07:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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! 04:07:00 INFO - Caught exception: HTTP Error 404: Not Found 04:07:00 INFO - Caught exception: HTTP Error 404: Not Found 04:07:00 INFO - Caught exception: HTTP Error 404: Not Found 04:07:00 INFO - trying https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:00 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:07:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:07:01 INFO - Downloaded 1361 bytes. 04:07:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:07:01 INFO - Using the following test package requirements: 04:07:01 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:07:01 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:07:01 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:07:01 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'jsshell-linux-x86_64.zip'], 04:07:01 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:07:01 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:07:01 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:07:01 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:07:01 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:07:01 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:07:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:07:01 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:07:01 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')]} 04:07:01 INFO - https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:07:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:07:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:07:01 INFO - retry: Failed, sleeping 30 seconds before retrying 04:07:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:07:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:07:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:07:31 INFO - retry: Failed, sleeping 60 seconds before retrying 04:08:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:08:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:08:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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! 04:08:31 INFO - Caught exception: HTTP Error 404: Not Found 04:08:31 INFO - Caught exception: HTTP Error 404: Not Found 04:08:31 INFO - Caught exception: HTTP Error 404: Not Found 04:08:31 INFO - trying https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:08:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:08:32 INFO - Downloaded 21766774 bytes. 04:08:32 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'] 04:08:32 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 04:08:33 INFO - Return code: 0 04:08:33 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 04:08: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')]} 04:08:33 INFO - https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:08:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:08:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:08:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:08:33 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:08:33 INFO - retry: Failed, sleeping 30 seconds before retrying 04:09:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:09:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:09:03 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:09:03 INFO - retry: Failed, sleeping 60 seconds before retrying 04:10:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:10:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:03 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:10:03 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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! 04:10:03 INFO - Caught exception: HTTP Error 404: Not Found 04:10:03 INFO - Caught exception: HTTP Error 404: Not Found 04:10:03 INFO - Caught exception: HTTP Error 404: Not Found 04:10:03 INFO - trying https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:10:03 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:10:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:10:04 INFO - Downloaded 21766774 bytes. 04:10:04 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'] 04:10:04 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 04:10:06 INFO - Return code: 0 04:10:06 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')]} 04:10:06 INFO - https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:10:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:10:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:10:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:10:06 INFO - retry: Failed, sleeping 30 seconds before retrying 04:10:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:10:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:10:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:10:36 INFO - retry: Failed, sleeping 60 seconds before retrying 04:11:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:11:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:11:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:11:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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! 04:11:36 INFO - Caught exception: HTTP Error 404: Not Found 04:11:36 INFO - Caught exception: HTTP Error 404: Not Found 04:11:36 INFO - Caught exception: HTTP Error 404: Not Found 04:11:36 INFO - trying https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:11:36 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:11:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:11:36 INFO - Downloaded 14413723 bytes. 04:11:36 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'] 04:11:36 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 04:11:37 INFO - Return code: 0 04:11:37 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')]} 04:11:37 INFO - https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:11:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:11:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:11:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:11:37 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:11:37 INFO - retry: Failed, sleeping 30 seconds before retrying 04:12:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:12:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:12:07 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:12:07 INFO - retry: Failed, sleeping 60 seconds before retrying 04:13:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:13:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:07 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:13:07 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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! 04:13:07 INFO - Caught exception: HTTP Error 404: Not Found 04:13:07 INFO - Caught exception: HTTP Error 404: Not Found 04:13:07 INFO - Caught exception: HTTP Error 404: Not Found 04:13:07 INFO - trying https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:07 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:13:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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 04:13:10 INFO - Downloaded 63785079 bytes. 04:13:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:10 INFO - mkdir: /builds/slave/test/properties 04:13:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:13:10 INFO - Writing to file /builds/slave/test/properties/build_url 04:13:10 INFO - Contents: 04:13:10 INFO - build_url:https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:13:11 INFO - Running post-action listener: _resource_record_post_action 04:13:11 INFO - Running post-action listener: set_extra_try_arguments 04:13:11 INFO - [mozharness: 2016-08-18 11:13:11.313716Z] Finished download-and-extract step (success) 04:13:11 INFO - [mozharness: 2016-08-18 11:13:11.313949Z] Running populate-webroot step. 04:13:11 INFO - Running pre-action listener: _resource_record_pre_action 04:13:11 INFO - Running main action method: populate_webroot 04:13:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:13:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:13:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:13:11 INFO - '--tppagecycles', 04:13:11 INFO - '7'], 04:13:11 INFO - 'tests': ['tsvgm']}}, 04:13:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:13:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:13:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:13:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:13:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:11 INFO - 'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:11 INFO - 'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['damp', 'tps']}, 04:13:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:11 INFO - 'tests': ['damp', 'tps']}, 04:13:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['dromaeo_dom']}, 04:13:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:13:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['basic_compositor_video']}, 04:13:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:13:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['a11yr', 04:13:11 INFO - 'ts_paint', 04:13:11 INFO - 'tpaint', 04:13:11 INFO - 'sessionrestore', 04:13:11 INFO - 'sessionrestore_no_auto_restore', 04:13:11 INFO - 'tabpaint']}, 04:13:11 INFO - 'other-e10s': {'tests': ['a11yr', 04:13:11 INFO - 'ts_paint', 04:13:11 INFO - 'tpaint', 04:13:11 INFO - 'sessionrestore', 04:13:11 INFO - 'sessionrestore_no_auto_restore', 04:13:11 INFO - 'tabpaint']}, 04:13:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:13:11 INFO - 'ts_paint', 04:13:11 INFO - 'tpaint', 04:13:11 INFO - 'sessionrestore', 04:13:11 INFO - 'sessionrestore_no_auto_restore', 04:13:11 INFO - 'tabpaint']}, 04:13:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:13:11 INFO - 'ts_paint', 04:13:11 INFO - 'tpaint', 04:13:11 INFO - 'sessionrestore', 04:13:11 INFO - 'sessionrestore_no_auto_restore', 04:13:11 INFO - 'tabpaint']}, 04:13:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['a11yr', 04:13:11 INFO - 'ts_paint', 04:13:11 INFO - 'tpaint', 04:13:11 INFO - 'sessionrestore', 04:13:11 INFO - 'sessionrestore_no_auto_restore', 04:13:11 INFO - 'tabpaint']}, 04:13:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['a11yr', 04:13:11 INFO - 'ts_paint', 04:13:11 INFO - 'tpaint', 04:13:11 INFO - 'sessionrestore', 04:13:11 INFO - 'sessionrestore_no_auto_restore', 04:13:11 INFO - 'tabpaint']}, 04:13:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['tsvgx', 04:13:11 INFO - 'tsvgr_opacity', 04:13:11 INFO - 'tart', 04:13:11 INFO - 'tscrollx', 04:13:11 INFO - 'cart']}, 04:13:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:13:11 INFO - 'tsvgr_opacity', 04:13:11 INFO - 'tart', 04:13:11 INFO - 'tscrollx', 04:13:11 INFO - 'cart']}, 04:13:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:11 INFO - 'talos_options': ['--disable-e10s'], 04:13:11 INFO - 'tests': ['tp5o']}, 04:13:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:11 INFO - 'tests': ['tp5o']}, 04:13:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:11 INFO - 'talos_options': ['--disable-e10s', 04:13:11 INFO - '--xperf_path', 04:13:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:11 INFO - 'tests': ['tp5n']}, 04:13:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:11 INFO - 'talos_options': ['--xperf_path', 04:13:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:11 INFO - 'tests': ['tp5n']}}, 04:13:11 INFO - 'talos.zip': {'path': '', 04:13:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:13:11 INFO - Running post-action listener: _resource_record_post_action 04:13:11 INFO - [mozharness: 2016-08-18 11:13:11.329465Z] Finished populate-webroot step (success) 04:13:11 INFO - [mozharness: 2016-08-18 11:13:11.329555Z] Running create-virtualenv step. 04:13:11 INFO - Running pre-action listener: _resource_record_pre_action 04:13:11 INFO - Running main action method: create_virtualenv 04:13:11 INFO - Creating virtualenv /builds/slave/test/build/venv 04:13:11 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 04:13:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:13:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:13:11 INFO - Using real prefix '/usr' 04:13:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:13:11 INFO - Installing distribute.............................................................................................................................................................................................done. 04:13:13 INFO - Installing pip.................done. 04:13:13 INFO - Return code: 0 04:13:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:13:13 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')]} 04:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:13 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')]} 04:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:13 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 0x16a0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1961180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', '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 04:13:13 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 04:13:13 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 04:13:13 INFO - Using env: {'DISPLAY': ':0', 04:13:13 INFO - 'HOME': '/home/cltbld', 04:13:13 INFO - 'LANG': 'en_US.UTF-8', 04:13:13 INFO - 'LANGUAGE': 'en_US:en', 04:13:13 INFO - 'LOGNAME': 'cltbld', 04:13:13 INFO - 'MAIL': '/var/mail/cltbld', 04:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:13 INFO - 'MOZ_NO_REMOTE': '1', 04:13:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:13 INFO - 'NO_EM_RESTART': '1', 04:13:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:13 INFO - 'PWD': '/builds/slave/test', 04:13:13 INFO - 'SHELL': '/bin/bash', 04:13:13 INFO - 'SHLVL': '1', 04:13:13 INFO - 'TERM': 'linux', 04:13:13 INFO - 'TMOUT': '86400', 04:13:13 INFO - 'USER': 'cltbld', 04:13:13 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:13 INFO - '_': '/tools/buildbot/bin/python'} 04:13:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13: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 04:13:14 INFO - Cleaning up... 04:13:14 INFO - Return code: 0 04:13:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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 0x16a0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1961180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', '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 04:13: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 04:13: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 04:13:14 INFO - Using env: {'DISPLAY': ':0', 04:13:14 INFO - 'HOME': '/home/cltbld', 04:13:14 INFO - 'LANG': 'en_US.UTF-8', 04:13:14 INFO - 'LANGUAGE': 'en_US:en', 04:13:14 INFO - 'LOGNAME': 'cltbld', 04:13:14 INFO - 'MAIL': '/var/mail/cltbld', 04:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:14 INFO - 'MOZ_NO_REMOTE': '1', 04:13:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:14 INFO - 'NO_EM_RESTART': '1', 04:13:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:14 INFO - 'PWD': '/builds/slave/test', 04:13:14 INFO - 'SHELL': '/bin/bash', 04:13:14 INFO - 'SHLVL': '1', 04:13:14 INFO - 'TERM': 'linux', 04:13:14 INFO - 'TMOUT': '86400', 04:13:14 INFO - 'USER': 'cltbld', 04:13:14 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:14 INFO - '_': '/tools/buildbot/bin/python'} 04:13:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:14 INFO - Downloading/unpacking psutil>=3.1.1 04:13:14 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 04:13:14 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 04:13:14 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 04:13:14 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 04:13:14 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 04:13:14 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 04:13:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:13:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:16 INFO - Installing collected packages: psutil 04:13:16 INFO - Running setup.py install for psutil 04:13:16 INFO - building 'psutil._psutil_linux' extension 04:13:16 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 04:13: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 04:13:17 INFO - building 'psutil._psutil_posix' extension 04:13: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 04:13: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 04:13:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:17 INFO - Successfully installed psutil 04:13:17 INFO - Cleaning up... 04:13:17 INFO - Return code: 0 04:13:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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 0x16a0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1961180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', '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 04:13: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 04:13: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 04:13:17 INFO - Using env: {'DISPLAY': ':0', 04:13:17 INFO - 'HOME': '/home/cltbld', 04:13:17 INFO - 'LANG': 'en_US.UTF-8', 04:13:17 INFO - 'LANGUAGE': 'en_US:en', 04:13:17 INFO - 'LOGNAME': 'cltbld', 04:13:17 INFO - 'MAIL': '/var/mail/cltbld', 04:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:17 INFO - 'MOZ_NO_REMOTE': '1', 04:13:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:17 INFO - 'NO_EM_RESTART': '1', 04:13:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:17 INFO - 'PWD': '/builds/slave/test', 04:13:17 INFO - 'SHELL': '/bin/bash', 04:13:17 INFO - 'SHLVL': '1', 04:13:17 INFO - 'TERM': 'linux', 04:13:17 INFO - 'TMOUT': '86400', 04:13:17 INFO - 'USER': 'cltbld', 04:13:17 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:17 INFO - '_': '/tools/buildbot/bin/python'} 04:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:13:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:13:19 INFO - Installing collected packages: mozsystemmonitor 04:13:19 INFO - Running setup.py install for mozsystemmonitor 04:13:19 INFO - Successfully installed mozsystemmonitor 04:13:19 INFO - Cleaning up... 04:13:20 INFO - Return code: 0 04:13:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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 0x16a0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1961180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', '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 04:13: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 04:13: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 04:13:20 INFO - Using env: {'DISPLAY': ':0', 04:13:20 INFO - 'HOME': '/home/cltbld', 04:13:20 INFO - 'LANG': 'en_US.UTF-8', 04:13:20 INFO - 'LANGUAGE': 'en_US:en', 04:13:20 INFO - 'LOGNAME': 'cltbld', 04:13:20 INFO - 'MAIL': '/var/mail/cltbld', 04:13:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:20 INFO - 'MOZ_NO_REMOTE': '1', 04:13:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:20 INFO - 'NO_EM_RESTART': '1', 04:13:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:20 INFO - 'PWD': '/builds/slave/test', 04:13:20 INFO - 'SHELL': '/bin/bash', 04:13:20 INFO - 'SHLVL': '1', 04:13:20 INFO - 'TERM': 'linux', 04:13:20 INFO - 'TMOUT': '86400', 04:13:20 INFO - 'USER': 'cltbld', 04:13:20 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:20 INFO - '_': '/tools/buildbot/bin/python'} 04:13:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:20 INFO - Downloading/unpacking jsonschema==2.5.1 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:13:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:22 INFO - Downloading functools32-3.2.3-2.tar.gz 04:13:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:13:22 INFO - warning: no files found matching '*.txt' 04:13:22 INFO - no previously-included directories found matching 'build' 04:13:22 INFO - no previously-included directories found matching 'dist' 04:13:22 INFO - no previously-included directories found matching '.git*' 04:13:22 INFO - Installing collected packages: jsonschema, functools32 04:13:22 INFO - Running setup.py install for functools32 04:13:22 INFO - warning: no files found matching '*.txt' 04:13:22 INFO - no previously-included directories found matching 'build' 04:13:22 INFO - no previously-included directories found matching 'dist' 04:13:22 INFO - no previously-included directories found matching '.git*' 04:13:22 INFO - Successfully installed jsonschema functools32 04:13:22 INFO - Cleaning up... 04:13:22 INFO - Return code: 0 04:13:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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 0x16a0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1961180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', '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 04:13: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 04:13: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 04:13:22 INFO - Using env: {'DISPLAY': ':0', 04:13:22 INFO - 'HOME': '/home/cltbld', 04:13:22 INFO - 'LANG': 'en_US.UTF-8', 04:13:22 INFO - 'LANGUAGE': 'en_US:en', 04:13:22 INFO - 'LOGNAME': 'cltbld', 04:13:22 INFO - 'MAIL': '/var/mail/cltbld', 04:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:23 INFO - 'MOZ_NO_REMOTE': '1', 04:13:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:23 INFO - 'NO_EM_RESTART': '1', 04:13:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:23 INFO - 'PWD': '/builds/slave/test', 04:13:23 INFO - 'SHELL': '/bin/bash', 04:13:23 INFO - 'SHLVL': '1', 04:13:23 INFO - 'TERM': 'linux', 04:13:23 INFO - 'TMOUT': '86400', 04:13:23 INFO - 'USER': 'cltbld', 04:13:23 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:23 INFO - '_': '/tools/buildbot/bin/python'} 04:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:13:23 INFO - Cleaning up... 04:13:23 INFO - Return code: 0 04:13:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:13:23 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')]} 04:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:23 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')]} 04:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:23 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 0x16a0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1961180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', '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 04:13:23 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 04:13:23 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 04:13:23 INFO - Using env: {'DISPLAY': ':0', 04:13:23 INFO - 'HOME': '/home/cltbld', 04:13:23 INFO - 'LANG': 'en_US.UTF-8', 04:13:23 INFO - 'LANGUAGE': 'en_US:en', 04:13:23 INFO - 'LOGNAME': 'cltbld', 04:13:23 INFO - 'MAIL': '/var/mail/cltbld', 04:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:23 INFO - 'MOZ_NO_REMOTE': '1', 04:13:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:23 INFO - 'NO_EM_RESTART': '1', 04:13:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:23 INFO - 'PWD': '/builds/slave/test', 04:13:23 INFO - 'SHELL': '/bin/bash', 04:13:23 INFO - 'SHLVL': '1', 04:13:23 INFO - 'TERM': 'linux', 04:13:23 INFO - 'TMOUT': '86400', 04:13:23 INFO - 'USER': 'cltbld', 04:13:23 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:23 INFO - '_': '/tools/buildbot/bin/python'} 04:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:23 INFO - Downloading/unpacking blobuploader==1.2.4 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:25 INFO - Downloading blobuploader-1.2.4.tar.gz 04:13:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:13:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:13:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13: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 04:13:26 INFO - Downloading docopt-0.6.1.tar.gz 04:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:13:26 INFO - Installing collected packages: blobuploader, requests, docopt 04:13:26 INFO - Running setup.py install for blobuploader 04:13:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:13:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:13:26 INFO - Running setup.py install for requests 04:13:26 INFO - Running setup.py install for docopt 04:13:26 INFO - Successfully installed blobuploader requests docopt 04:13:26 INFO - Cleaning up... 04:13:26 INFO - Return code: 0 04:13:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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 0x16a0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1961180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', '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 04:13: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 04:13: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 04:13:26 INFO - Using env: {'DISPLAY': ':0', 04:13:26 INFO - 'HOME': '/home/cltbld', 04:13:26 INFO - 'LANG': 'en_US.UTF-8', 04:13:26 INFO - 'LANGUAGE': 'en_US:en', 04:13:26 INFO - 'LOGNAME': 'cltbld', 04:13:26 INFO - 'MAIL': '/var/mail/cltbld', 04:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:26 INFO - 'MOZ_NO_REMOTE': '1', 04:13:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:26 INFO - 'NO_EM_RESTART': '1', 04:13:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:26 INFO - 'PWD': '/builds/slave/test', 04:13:26 INFO - 'SHELL': '/bin/bash', 04:13:26 INFO - 'SHLVL': '1', 04:13:26 INFO - 'TERM': 'linux', 04:13:26 INFO - 'TMOUT': '86400', 04:13:26 INFO - 'USER': 'cltbld', 04:13:26 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:26 INFO - '_': '/tools/buildbot/bin/python'} 04:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:27 INFO - Running setup.py (path:/tmp/pip-1pEy_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:27 INFO - Running setup.py (path:/tmp/pip-D6QmMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:27 INFO - Running setup.py (path:/tmp/pip-Pz9xwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:27 INFO - Running setup.py (path:/tmp/pip-NY85va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:27 INFO - Running setup.py (path:/tmp/pip-s3NxkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:27 INFO - Running setup.py (path:/tmp/pip-mXN9W1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:27 INFO - Running setup.py (path:/tmp/pip-qTSYvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:27 INFO - Running setup.py (path:/tmp/pip-GNZQoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:27 INFO - Running setup.py (path:/tmp/pip-9nNp2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:27 INFO - Running setup.py (path:/tmp/pip-WG7y6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:27 INFO - Running setup.py (path:/tmp/pip-v7N7Nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:27 INFO - Running setup.py (path:/tmp/pip-9ErgoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:27 INFO - Running setup.py (path:/tmp/pip-w06b3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:27 INFO - Running setup.py (path:/tmp/pip-G1XNMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:28 INFO - Running setup.py (path:/tmp/pip-mQzrlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:28 INFO - Running setup.py (path:/tmp/pip-g6Ngpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:28 INFO - Running setup.py (path:/tmp/pip-xPpqtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:13:28 INFO - Running setup.py install for manifestparser 04:13:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:13:28 INFO - Running setup.py install for mozcrash 04:13:28 INFO - Running setup.py install for mozdebug 04:13:28 INFO - Running setup.py install for mozdevice 04:13:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:13:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:13:28 INFO - Running setup.py install for mozfile 04:13:28 INFO - Running setup.py install for mozhttpd 04:13:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:13:28 INFO - Running setup.py install for mozinfo 04:13:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:13:28 INFO - Running setup.py install for mozInstall 04:13:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Running setup.py install for mozleak 04:13:29 INFO - Running setup.py install for mozlog 04:13:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Running setup.py install for moznetwork 04:13:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Running setup.py install for mozprocess 04:13:29 INFO - Running setup.py install for mozprofile 04:13:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Running setup.py install for mozrunner 04:13:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:13:29 INFO - Running setup.py install for mozscreenshot 04:13:29 INFO - Running setup.py install for moztest 04:13:29 INFO - Running setup.py install for mozversion 04:13:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:13:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:13:30 INFO - Cleaning up... 04:13:30 INFO - Return code: 0 04:13:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13:30 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')]} 04:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:30 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')]} 04:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:30 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 0x16a0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1961180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', '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 04:13:30 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 04:13:30 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 04:13:30 INFO - Using env: {'DISPLAY': ':0', 04:13:30 INFO - 'HOME': '/home/cltbld', 04:13:30 INFO - 'LANG': 'en_US.UTF-8', 04:13:30 INFO - 'LANGUAGE': 'en_US:en', 04:13:30 INFO - 'LOGNAME': 'cltbld', 04:13:30 INFO - 'MAIL': '/var/mail/cltbld', 04:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:30 INFO - 'MOZ_NO_REMOTE': '1', 04:13:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:30 INFO - 'NO_EM_RESTART': '1', 04:13:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:30 INFO - 'PWD': '/builds/slave/test', 04:13:30 INFO - 'SHELL': '/bin/bash', 04:13:30 INFO - 'SHLVL': '1', 04:13:30 INFO - 'TERM': 'linux', 04:13:30 INFO - 'TMOUT': '86400', 04:13:30 INFO - 'USER': 'cltbld', 04:13:30 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:30 INFO - '_': '/tools/buildbot/bin/python'} 04:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:30 INFO - Running setup.py (path:/tmp/pip-uidGmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:30 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)) 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:30 INFO - Running setup.py (path:/tmp/pip-IZ8Jtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:30 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)) 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:30 INFO - Running setup.py (path:/tmp/pip-o4w9Oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:30 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)) 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:30 INFO - Running setup.py (path:/tmp/pip-t0A5tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13: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)) 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:30 INFO - Running setup.py (path:/tmp/pip-NQhejz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13: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)) 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:30 INFO - Running setup.py (path:/tmp/pip-IV20hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13: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)) 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:30 INFO - Running setup.py (path:/tmp/pip-Uweb5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13: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)) 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:30 INFO - Running setup.py (path:/tmp/pip-b5f_jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13: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)) 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:30 INFO - Running setup.py (path:/tmp/pip-iZE_mF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13: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)) 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:30 INFO - Running setup.py (path:/tmp/pip-hiYH7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:31 INFO - Running setup.py (path:/tmp/pip-WhGFvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:31 INFO - Running setup.py (path:/tmp/pip-boiCKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:31 INFO - Running setup.py (path:/tmp/pip-1GoZDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:31 INFO - Running setup.py (path:/tmp/pip-zRQ7vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:31 INFO - Running setup.py (path:/tmp/pip-ImPePh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:31 INFO - Running setup.py (path:/tmp/pip-Cz8jom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:31 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)) 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:31 INFO - Running setup.py (path:/tmp/pip-eJ2HKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:31 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)) 04:13:31 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)) 04:13:31 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)) 04:13:31 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)) 04:13:31 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)) 04:13:31 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)) 04:13:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13:31 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 04:13:31 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 04:13:31 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 04:13:31 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 04:13:31 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 04:13:31 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 04:13:33 INFO - Downloading blessings-1.6.tar.gz 04:13:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:13:33 INFO - Installing collected packages: blessings 04:13:33 INFO - Running setup.py install for blessings 04:13:33 INFO - Successfully installed blessings 04:13:33 INFO - Cleaning up... 04:13:33 INFO - Return code: 0 04:13:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:13:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:33 INFO - Reading from file tmpfile_stdout 04:13:33 INFO - Current package versions: 04:13:33 INFO - argparse == 1.2.1 04:13:33 INFO - blessings == 1.6 04:13:33 INFO - blobuploader == 1.2.4 04:13:33 INFO - docopt == 0.6.1 04:13:33 INFO - functools32 == 3.2.3-2 04:13:33 INFO - jsonschema == 2.5.1 04:13:33 INFO - manifestparser == 1.1 04:13:33 INFO - mozInstall == 1.12 04:13:33 INFO - mozcrash == 0.17 04:13:33 INFO - mozdebug == 0.1 04:13:33 INFO - mozdevice == 0.48 04:13:33 INFO - mozfile == 1.2 04:13:33 INFO - mozhttpd == 0.7 04:13:33 INFO - mozinfo == 0.9 04:13:33 INFO - mozleak == 0.1 04:13:33 INFO - mozlog == 3.3 04:13:33 INFO - moznetwork == 0.27 04:13:33 INFO - mozprocess == 0.23 04:13:33 INFO - mozprofile == 0.28 04:13:33 INFO - mozrunner == 6.12 04:13:33 INFO - mozscreenshot == 0.1 04:13:33 INFO - mozsystemmonitor == 0.3 04:13:33 INFO - moztest == 0.7 04:13:33 INFO - mozversion == 1.4 04:13:33 INFO - psutil == 3.1.1 04:13:33 INFO - requests == 1.2.3 04:13:33 INFO - wsgiref == 0.1.2 04:13:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13: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')]} 04:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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')]} 04:13:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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 0x16a0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1961180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', '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 04:13: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 04:13: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 04:13:33 INFO - Using env: {'DISPLAY': ':0', 04:13:33 INFO - 'HOME': '/home/cltbld', 04:13:33 INFO - 'LANG': 'en_US.UTF-8', 04:13:33 INFO - 'LANGUAGE': 'en_US:en', 04:13:33 INFO - 'LOGNAME': 'cltbld', 04:13:33 INFO - 'MAIL': '/var/mail/cltbld', 04:13:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:33 INFO - 'MOZ_NO_REMOTE': '1', 04:13:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:33 INFO - 'NO_EM_RESTART': '1', 04:13:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:33 INFO - 'PWD': '/builds/slave/test', 04:13:33 INFO - 'SHELL': '/bin/bash', 04:13:33 INFO - 'SHLVL': '1', 04:13:33 INFO - 'TERM': 'linux', 04:13:33 INFO - 'TMOUT': '86400', 04:13:33 INFO - 'USER': 'cltbld', 04:13:33 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:33 INFO - '_': '/tools/buildbot/bin/python'} 04:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:34 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)) 04:13:34 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)) 04:13:34 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)) 04:13:34 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)) 04:13:34 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)) 04:13:34 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)) 04:13:34 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)) 04:13:34 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)) 04:13:34 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)) 04:13:34 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)) 04:13:34 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)) 04:13:34 INFO - Cleaning up... 04:13:34 INFO - Return code: 0 04:13:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:13:34 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')]} 04:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:34 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')]} 04:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:34 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 0x16a0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1961180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', '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 04:13:34 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 04:13:34 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 04:13:34 INFO - Using env: {'DISPLAY': ':0', 04:13:34 INFO - 'HOME': '/home/cltbld', 04:13:34 INFO - 'LANG': 'en_US.UTF-8', 04:13:34 INFO - 'LANGUAGE': 'en_US:en', 04:13:34 INFO - 'LOGNAME': 'cltbld', 04:13:34 INFO - 'MAIL': '/var/mail/cltbld', 04:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:34 INFO - 'MOZ_NO_REMOTE': '1', 04:13:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:34 INFO - 'NO_EM_RESTART': '1', 04:13:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:34 INFO - 'PWD': '/builds/slave/test', 04:13:34 INFO - 'SHELL': '/bin/bash', 04:13:34 INFO - 'SHLVL': '1', 04:13:34 INFO - 'TERM': 'linux', 04:13:34 INFO - 'TMOUT': '86400', 04:13:34 INFO - 'USER': 'cltbld', 04:13:34 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:34 INFO - '_': '/tools/buildbot/bin/python'} 04:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:13:34 INFO - Cleaning up... 04:13:34 INFO - Return code: 0 04:13:34 INFO - Running post-action listener: _resource_record_post_action 04:13:34 INFO - Running post-action listener: _start_resource_monitoring 04:13:34 INFO - Starting resource monitoring. 04:13:34 INFO - [mozharness: 2016-08-18 11:13:34.400047Z] Finished create-virtualenv step (success) 04:13:34 INFO - [mozharness: 2016-08-18 11:13:34.400479Z] Running install step. 04:13:34 INFO - Running pre-action listener: _resource_record_pre_action 04:13:34 INFO - Running main action method: install 04:13:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:34 INFO - Reading from file tmpfile_stdout 04:13:34 INFO - Detecting whether we're running mozinstall >=1.0... 04:13:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:13:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:13:34 INFO - Reading from file tmpfile_stdout 04:13:34 INFO - Output received: 04:13:34 INFO - Usage: mozinstall [options] installer 04:13:34 INFO - Options: 04:13:34 INFO - -h, --help show this help message and exit 04:13:34 INFO - -d DEST, --destination=DEST 04:13:34 INFO - Directory to install application into. [default: 04:13:34 INFO - "/builds/slave/test"] 04:13:34 INFO - --app=APP Application being installed. [default: firefox] 04:13:34 INFO - mkdir: /builds/slave/test/build/application 04:13: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'] 04:13: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 04:13:51 INFO - Reading from file tmpfile_stdout 04:13:51 INFO - Output received: 04:13:51 INFO - /builds/slave/test/build/application/firefox/firefox 04:13:51 INFO - Running post-action listener: _resource_record_post_action 04:13:51 INFO - [mozharness: 2016-08-18 11:13:51.590150Z] Finished install step (success) 04:13:51 INFO - [mozharness: 2016-08-18 11:13:51.590428Z] Running run-tests step. 04:13:51 INFO - Running pre-action listener: _resource_record_pre_action 04:13:51 INFO - Running main action method: run_tests 04:13:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:13:51 INFO - Python 2.7.3 04:13:51 INFO - Return code: 0 04:13:51 INFO - grabbing minidump binary from tooltool 04:13:51 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')]} 04:13:51 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 0x195f3b0>, '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 0x19602b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:13:51 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 04:13:51 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 04:13:51 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 04:13:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:13:51 INFO - Return code: 0 04:13:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:13:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:13:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:13:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:13:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:13:51 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/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 04:13:51 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/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 04:13:51 INFO - Using env: {'DISPLAY': ':0', 04:13:51 INFO - 'HOME': '/home/cltbld', 04:13:51 INFO - 'LANG': 'en_US.UTF-8', 04:13:51 INFO - 'LANGUAGE': 'en_US:en', 04:13:51 INFO - 'LOGNAME': 'cltbld', 04:13:51 INFO - 'MAIL': '/var/mail/cltbld', 04:13:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:13:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:51 INFO - 'MOZ_NO_REMOTE': '1', 04:13:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:13:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:51 INFO - 'NO_EM_RESTART': '1', 04:13:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:51 INFO - 'PWD': '/builds/slave/test', 04:13:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:13:51 INFO - 'SHELL': '/bin/bash', 04:13:51 INFO - 'SHLVL': '1', 04:13:51 INFO - 'TERM': 'linux', 04:13:51 INFO - 'TMOUT': '86400', 04:13:51 INFO - 'USER': 'cltbld', 04:13:51 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598', 04:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:51 INFO - '_': '/tools/buildbot/bin/python'} 04:13:51 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/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 04:13:51 INFO - mozversion application_buildid: 20160817151514 04:13:51 INFO - mozversion application_changeset: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c 04:13:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:13:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:13:51 INFO - mozversion application_name: Firefox 04:13:51 INFO - mozversion application_remotingname: firefox-dev 04:13:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:51 INFO - mozversion application_vendor: Mozilla 04:13:51 INFO - mozversion application_version: 50.0a2 04:13:51 INFO - mozversion platform_buildid: 20160817151514 04:13:51 INFO - mozversion platform_changeset: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c 04:13:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:51 INFO - mozversion platform_version: 50.0a2 04:13:51 INFO - using testdate: 1471518831 04:13:51 INFO - actual date: 1471518831 04:13:51 INFO - starting webserver on 'localhost:59629' 04:13:51 INFO - SUITE-START | Running 2 tests 04:13:51 INFO - TEST-START | dromaeo_css 04:13:51 INFO - operating with platform_type : linux_ 04:13:51 INFO - Initialising browser for dromaeo_css test... 04:13:51 INFO - TEST-INFO | started process 32365 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ_rN4K/profile http://localhost:59629/getInfo.html) 04:13:56 INFO - PROCESS | 32365 | __metrics Screen width/height:1600/1200 04:13:56 INFO - PROCESS | 32365 | colorDepth:24 04:13:56 INFO - PROCESS | 32365 | Browser inner width/height: 1024/697 04:13:56 INFO - PROCESS | 32365 | __metrics 04:13:57 INFO - PROCESS | 32365 | 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:13:57 INFO - PROCESS | 32365 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:13:58 INFO - TEST-INFO | 32365: exit 0 04:13:58 INFO - Browser initialized. 04:13:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:13:58 INFO - TEST-INFO | started process 32453 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ_rN4K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:10 INFO - PROCESS | 32453 | RSS: Main: 145408000 04:14:10 INFO - PROCESS | 32453 | 04:15:00 INFO - PROCESS | 32453 | 1471518900947 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] 04:17:09 INFO - PROCESS | 32453 | Cycle 1(1): loaded http://localhost:59629/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59629/tests/dromaeo/cssquery-ext.html) 04:17:11 INFO - PROCESS | 32453 | RSS: Main: 225124352 04:17:11 INFO - PROCESS | 32453 | 04:20:13 INFO - PROCESS | 32453 | Cycle 1(1): loaded http://localhost:59629/tests/dromaeo/cssquery-ext.html (next: http://localhost:59629/tests/dromaeo/cssquery-jquery.html) 04:20:14 INFO - PROCESS | 32453 | RSS: Main: 235683840 04:20:14 INFO - PROCESS | 32453 | 04:23:16 INFO - PROCESS | 32453 | Cycle 1(1): loaded http://localhost:59629/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59629/tests/dromaeo/cssquery-mootools.html) 04:23:16 INFO - PROCESS | 32453 | RSS: Main: 173907968 04:23:16 INFO - PROCESS | 32453 | 04:26:18 INFO - PROCESS | 32453 | Cycle 1(1): loaded http://localhost:59629/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59629/tests/dromaeo/cssquery-prototype.html) 04:26:19 INFO - PROCESS | 32453 | RSS: Main: 175140864 04:26:19 INFO - PROCESS | 32453 | 04:29:21 INFO - PROCESS | 32453 | Cycle 1(1): loaded http://localhost:59629/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59629/tests/dromaeo/cssquery-yui.html) 04:29:22 INFO - PROCESS | 32453 | RSS: Main: 176553984 04:29:22 INFO - PROCESS | 32453 | 04:32:25 INFO - PROCESS | 32453 | Cycle 1(1): loaded http://localhost:59629/tests/dromaeo/cssquery-yui.html (next: http://localhost:59629/tests/dromaeo/cssquery-dojo.html) 04:32:25 INFO - PROCESS | 32453 | RSS: Main: 256937984 04:32:25 INFO - PROCESS | 32453 | 04:32:25 INFO - PROCESS | 32453 | __start_tp_report 04:32:25 INFO - PROCESS | 32453 | _x_x_mozilla_page_load 04:32:25 INFO - PROCESS | 32453 | _x_x_mozilla_page_load_details 04:32:25 INFO - PROCESS | 32453 | |i|pagename|runs| 04:32:25 INFO - PROCESS | 32453 | |0;dojo.html;3215.6462789093202;3241.4165450218966;3246.9837650811755;3270.3132342208137;3303.454929936559;5615.859603509909;5714.828555143342;5724.34170070442;5725.312962444502;5747.109198074295;5577.163425486179;5627.7467513961865;5634.830955071344;5636.379998200195;5672.120821272699;4969.652124351297;5013.924791128136;5037.093323201813;5078.923816142761;5121.718305493197;5076.314697515825;5130.538251557359;5133.512316329943;5139.408967968694;5139.897202055957;5032.4967503249745;5063.012712521041;5078.542931136197;5078.695278283296;5136;5180.507851754077;5182.300389447433;5194.246834209019;5202.193659982709;5221.477852214767;4920.655554111214;4989.950100498985;5008.774605142778;5065.1895696688725;5071.112555302844;5489.176623506466;5502.697351645658;5515.034868897927;5575.358833734132;5575.581831362664;6025.6987150642735;6049.122877182828;6054;6059.030555111205;6063.18147050147;6026.9095963560585;6061.51507879373;6066.090086487018;6091.817245482598;6101.908471372933;5676.120201368794;5719.227904232917;5728;5729.28383952006;5729.828105156852;5850.444207800252;6208.286047104551;6209.254889413209;6209.844753881099;6213.565050446426;5816.94183058164;5881.558883083786;5891.263592050994;5989.2812862456785;6008.609440386448;6035.879282414415;6041.184440100618;6067.302260240041;6076.179715738407;6082.726277317532;5784.045632470679;6010.699465026732;6024.337322894478;6026.367231440723;6026.367231440723;5928.555358348141;5993.250843644544;6014.1580178775;6062.363451837582;6113.57204995655;4642.048380082075;4642.744649044335;4643.349931009661;4646.767661616906;4666.4633567139535;4000.0399904023407;4153.252414565347;4173.791310434466;4178.039051018282;4191.580841915784;5630.0428927081675;5634.6337488063145;5665.5184309333345;5711;5747.5114615257335;6040.7583696651645;6089.42150495711;6093.2078829751845;6101.267847858197;6120.510359171256;5572.331320241516;5715.685637289906;5718.256626638594;5724;5729.455701708331;5056.140456122468;5085.516875896784;5090.211017292326;5140.074786538385;5158.0199762045095;6326.272478665012;6336.429721325103;6336.714847831859;6338.302786693553;6344.302126766052;5860.267466566679;6122.989706698345;6136.478399335928;6138.202033735586;6147.385261473817;6175.166352542529;6250.906236406367;6251.531135164972;6284.968575157095;6334.239891213084;5932.139839723302;6003.339632640494;6007.699615019319;6009.248843894513;6027.879442411039;5889.234399528034;6092.268927728701;6097.908531372122;6101.7559297627595;6104.32852386246;5804.82585522435;5864.970675146597;5870.031444811559;5922.7927022554;5943.257092863392;5894.2926848778425;5897.882042359214;5900.1149827526215;5903.085021821625;5904.645721256738;5647.5481961442165;5828.854278642898;5872.500837428866;5879;5881.235439392852;5845.09401042839;5880.353161152184;5902.2327097477055;5989.7304621293015;5993.670348130895;5815.389384114607;5828.417158284137;5828.533717302521;5834.241548598655;5834.854128646648 04:32:25 INFO - PROCESS | 32453 | |1;ext.html;606.2906399512573;645.8223988403189;664.2328111031757;683.8700646877093;689.2315068698402;36910.815445922766;48556.75721621391;49759.50240497594;49971.99999999991;49998.50001499984;49012.754936225356;49431.75284123574;49690.254646180336;49723.50276497243;49726.25410618843;8536.530490823003;9376.484293363845;9522.904770952271;9539.141477267045;9540.379875308117;11042.503087361089;11322.886771132265;11334.659960201167;11705.59030433935;11712.648620541355;11534.423278836024;11619.244749091324;11637.650870473899;11642;11644.359560224184;11275.267107637977;11297.209195356287;11340.716482088013;11350.943245283803;11363.886361136407;11247.66257012291;11345.978861902386;11349.659510214708;11354.60258890943;11355.43222838863;47059;47144.29283560715;47384.76307618475;47398.52601473976;47401.76299118482;3891.1439483313625;3944.3886197639413;4648.907021859578;4782.856514304576;4801.783919723586;35202.29595408103;35751;35789.46315805265;35893.10267243314;37451.25097498035;4740.907933305685;4748.07412554552;4813.205821039524;4813.350197723297;4867.440244371872;391674;391768;394420;395327;395483;69019.65490172517;69398.65300673465;69573.8691758872;69692.30307696959;69711.30288697049;40805.38791918124;41646.58353416427;41683;73067.63466182741;73200.63399683074;18512.722309165372;18615.81384186168;18782.624347513247;18792.43622691294;18816.24735010584;12959.222446653232;14189.85810141906;14195.645108872155;14992.47526336573;15515.146666933428;21659.67510487344;21791.891040544695;21827.345179644635;21836.89081554614;21871.890640546695;7654.311111999947;7672.693092276247;7704.922950770532;7720.884186737204;7754.224577542201;1075.9246852720391;1086.3481910853425;1089.2048804372694;1171.1158075652827;1201.327256736213;35524.46713299303;35998.82000590033;36286.09284767902;36297.27405451876;36298.27403451916;1138.3226979947083;1145.627671006926;1159.191661008829;1178.505027888289;1238.2322959765004;17296;18391.540211494554;18400.447986560426;18427.26290948347;18430.723539146922;652631;654955;655304;655517;661595;66067.66966165138;66705.3329466699;66721.33278667249;66833.33166668368;66954;1352.3981828086407;1476.0995792566741;1478.8299345575397;1487.6132205626616;1489.3967942983109;34564.65435345614;37036.44445333268;37205;37402;37454.62545374512;56978.145327819286;57496.425035749104;57516.71241643765;57676.134857978;57711.422885770604;490748.18503629015;494026;496569;497049;562380;46683.29975050445;49060.75469622629;49272.75363623159;49418.258726118416;50177.49822501728;54549;54748.72625636846;54977.45022549723;55634;56480;45276;47605.523944761495;47994.76002619965;48038.759806200746;49198;45196.28408750348;45361.546384537054;46443;47403.762981184875;48068.27897581469;44820.10359792851;47015;47868;48333.27500087431;48460.757696211294;38481;41264;41404.171916560896;41478;42140.78929605332;30660.846695766377;30998.84500577573;31121.221969450944;31683.84158079195;31796.36407271888 04:32:25 INFO - PROCESS | 32453 | |2;jquery.html;397.08471972104286;409.4615580511625;426.48821414302836;431.6740860650208;441.4283502863791;15928.362865485382;21481.67777483335;21672.674909876343;21699.132034718612;21983.23058692942;20827.062782174802;21051.789482105174;21086.15655373787;21154.471138221514;21315.147394104217;5573.832785016456;5932.377100404438;5983.521318294526;5987.461128498434;5992.670403127826;5715.77136914525;5896.056630939051;5961.105834124873;5977.073553599199;5977.372375900533;5812.825615231529;5835.183074369592;5838.591298609099;5841.357450680422;5849.8830023399505;5614.606977511576;5744.310682718101;5818.883622327572;5834.679092649882;5838.883222335551;5202.271681964528;5250.894982100374;5270.841874743764;5273.235380869791;5274.789008439658;24395.14616988397;24427.511449770904;24822.875885620637;24953;25129.62305565418;2494;2551.59174532074;2569.897204111833;2629.2375211188732;3205.9038228853174;15275.541733748005;15474;15504.379824806994;16034;16281.83718162815;2036.3992622176493;2050.456628993318;2066.90698918549;2073.9896300518553;2109.6519074352714;67747.32252677459;70036.74091185156;71009;86347.56826215828;87569;9352.766180845398;11408.201425900274;11635.603727552578;11719.355435450962;11750.059995200363;13213;13891;13920.373583188579;14267.571972840831;14293.57119286423;8367.406104107746;8377.287930525852;8482.660693572188;8539.829203415897;8587.484750914966;8546.273566746897;8645.481271123752;8658.134186581417;8799.120087991278;8810.207081362709;4377.956220437819;4397.670174736908;4417.536158703354;4495.348174514677;4569.314602809605;2086.4262327859656;2119.1841141160457;2126.989365053165;2132.758207524554;2173.0330003148447;930.265090578449;986.9062439068277;994.269212129099;1010.1312870930993;1076.806174888512;20884;21037.15851365959;21489.46276343104;21521.688077428647;21717.34847954564;1029.907308342238;1034.2760067952463;1062.0759938853093;1064.5688496159069;1095.7205912492423;11651.53393864253;11824.763504729857;12054.397280136134;12068.818967715491;12087.274763514217;478123;485735;485959;503224;503683;96172.51913740387;96365;96411.51794240985;96863;98377;1014.599233302856;1070.6948519671855;1096.873859506167;1110.294962698676;1121.1311233793874;18791.436256912315;19342.4197274082;19448.4165475036;19561.21755129779;19608.8039119607;45062.32406513971;45623;46345.76827115843;47209;47927.28109078297;64543;64849.67575162094;65193.67403162954;66537;66932.665336673;9023.368364214704;9351.48566828831;9354.158125768714;9400.670976515788;9585.808283834145;11918.702032449259;13594.184348939096;13799.379027943565;14233.146011239358;14558.781618275947;12303.261804291771;12303.630891073282;12419.254844709347;12504;12715.934484320465;12054.879451205376;12260.32568208757;12337.876621233672;12605.93697031509;12759.744805104032;6230.532710046674;8003.919960800317;12455.567609724998;12739.872601274239;12886.67783305425;9218.539073046455;9234.168924796531;9296.209822165192;9357.906420935977;9616.182624476718;9356.578953946953;11524.94237528807;12032.879671203526;12401.503939842305;12820.743585128432 04:32:25 INFO - PROCESS | 32453 | |3;mootools.html;1560.6176486760742;1606.1166358502821;1801.6486785076906;1872.8220819022197;1916.7987361327052;8620.956895215522;9276.489793061395;9279.814403711931;9281.071892810724;9284;8283.875741863862;8558.101399353072;8944.59749311281;9001.099890010995;9023.278137748981;2386.9283921482383;2444.7799698027175;2447.229122826313;2448.5959816630234;2451.2156110044793;2413.3242692046256;2460.6186041163646;2464.827462077655;2481.491294284667;2481.627755836621;2165.4153378587853;2231.5313784105274;2231.9107235710553;2232.4530490029965;2388.6775285336516;2307.711536057993;2373.0982226753895;2380.73811880693;2383.2016274548046;2384.058296972686;2422.9757702422926;2426.757324267559;2431.720352159489;2450.816188785848;2453.7178224504232;9061.59222834974;9188.494632795193;9214.907850921472;9218.124278193627;9223.07769223079;5580.525655319303;5740.5981581289325;5745.052066409037;5754.597178197528;5756.913646295309;3376.628570857228;3467.6358982306747;3523.365794157051;3526.929461410783;3539.681428671406;1191.7139886427283;1229.3484453239798;1231.0889941443268;1246.2211118051218;1254.9686257843537;12368.13423060386;12688.74622507554;12688.80966785499;12720.809187862189;12785;4832.782524786393;4891.657583969088;4927.285543596158;4958.578520825698;5031.320771695848;11151.944240278908;11153.10775137987;11153.163512736577;11158.1631377647;11165.441727913572;5600.775968961196;5611.410801865745;5615.157726341082;5622.465865742748;5622.887542249132;5078.263651770472;5114.156164232934;5133.974330128325;5193.246979187997;5206.973965130226;4320.805563749639;4335.523092459827;4356.08522210333;4412.713173643703;4432.891777055736;1369.2263870912902;1415.978760318596;1419.1130543410368;1428.550006747871;1436.9353379097965;1033.4006276359694;1036.2694300518117;1044.535181844072;1045.0698877998586;1048.8479170520231;6326.304106548276;6332.936670633235;6347.873042539123;6468.126802881645;6517.902231466532;483.47059969333526;486.4648886225195;489.26121556450016;490.2401278019056;504.14798989707276;4283.464566929134;4318.373835793793;4444.266695995189;4461.375407442961;4550.817967281272;11712;12046.277223366626;12074.456649450796;12104.031677465782;12110.39448027595;10939.45302734876;11620.76758464826;11654.825177622342;11806.232594881478;12015.15893887437;8519.701810436605;8728.214460698568;8735.825283494294;8742.038375778659;8745.431546949556;6534.542582019213;6545.181852268466;6562.606243625398;6625.238097618835;6807.910734282491;10569.207309451667;10776.622818201326;10807.081398080936;10832.945835270773;10952.726181845517;11205.551777929119;11220.607278745205;11252.493637786156;11259.662210133709;11310.773784524099;8029.237222463857;8162.020557532896;8167.224113709426;8209.917900821154;8433.789155271168;8871.423357481743;9035.051319611346;9036.141566551167;9039.276857851224;9045.050269721585;7935.2064793520185;8063.25849211271;8175;8227.177282271725;8377.874331884905;7571.318581327487;7648.579328137006;7665.271799179069;7695.461317707706;7770.650320735694;7500.662470188964;7575.318221360104;7607.923920760722;7609.619519024137;7610.771676849703;5611.074172761449;5637.126245431965;5653;5673.971630141823;5816.3892791258;5449.100898351728;5492.972535137458;5564.638298510584;5609.4390560943575;5691.032524470767 04:32:25 INFO - PROCESS | 32453 | |4;prototype.html;343.64089526444866;345.8599267296744;351.3166890398175;358.8510768031267;359.9586047604525;5590.972045139773;5621.831345059644;5669.971650141749;5674.943250567493;5679.119736440848;5474.671519708811;5502.944970550293;5587.245721827552;5588.608797384175;5594.049011668016;3845.5577608574954;4109.650679692222;4242.53332133465;4281.657467402601;4288.463942007249;4295.935560966573;4301.268784306675;4303.462067241595;4304.956950430487;4323.82704691812;4293.978530107361;4300.193918554199;4304.354346847982;4321.459817522809;4331.501877284106;4133.958660413387;4230.598093181143;4321.762303073329;4330.155619654169;4336.414584031147;3805.7906815125157;3813.828377723009;3818.6563209311294;3829.349010668193;3862.246861861938;5375.172441369914;5400.162974738922;5423.2407462954825;5427.62006659534;5444.183372494118;4766.666333356667;4915.754212289371;4948.7278199699;4952.727599981999;4988.725620090893;5692.231548740909;5710.65736055838;5724.084146536534;5737.512311453532;5756.481916627524;4214.85248016321;4217.662586993033;4220.2192594369835;4222.493300803923;4253.383259427396;5691.715414229272;5853.414658534155;5858.83223207416;6041.395860413967;6092.939070609325;4290.206311832321;4331.545187755303;4341.175176716477;4351.586599273064;4361.018770776614;4392.143532011291;4401.317795741666;4405.581469760367;4423.800928958204;4425.424694789656;4901.926471102936;4989.201727723546;5007.246014691905;5008.9982003599425;5027.547520723152;5749.396313387034;5794.333651630032;5795.739191736382;5833.7374818133285;5846.210761547221;1508.374024779704;1531.946381876629;1532.762421824619;1532.9003614765004;1536.9462068827538;943.7923656795494;979.4025644356936;986.063239922077;998.9950050249847;1016.6390931219458;4876.561109500162;4958.7024778513405;4973.35346404965;4989.977054703777;4998.2002879539095;5218.2955301034635;5237.6333656643665;5265.552428043659;5302.912902854905;5317.7872885084935;4993.201087825931;5016.36640763602;5076.492350764894;5245.632805703641;5646.802361917313;3141.9057428277188;3176.0154352150907;3187.824669643192;3189.2983543620367;3189.9521507176946;6066.666333351708;6196.566240363131;6198.783042593487;6230.784996925556;6248.625082495065;6016.127661489059;6046.153538504613;6070.423309785563;6072.99795533732;6090.451859332593;6185.907211391833;6188.597741146901;6195.380461953769;6197.721102550396;6226.221722284715;5842.342096515716;5865.501432378125;5906.291245050621;5940.79207227745;6011.609245399035;5857.853553661193;5881.2942446906645;5911.7635294587735;5930.547015981112;5932.406759324033;5832.617082092461;5880.647161170344;6018.458338749533;6035.336113027652;6161.229846269216;4144.91710165801;4281;4296.699231053796;4300.956990430056;4318.711083125948;5985.832762611249;5998.790042348497;6008.909866351921;6035.668038257762;6048.667323297261;5602.971985140048;5788.21859049023;5792.536597072309;5855.238818953509;5888.882222355443;5795.536357091339;5833.504152146946;5858.9865941829985;5861.736221869941;5867.559933004956;5808.419158084158;5830.679312637845;5833.912491312549;5869.8239052828485;5872.853178670567;5737.053386191232;5843.29880414353;5862.94137058624;5868.178455016387;5901.822945311647;5728.427157284238;5744.281964754406;5747.672192761938;5748.310202775694;5761.769529218785 04:32:25 INFO - PROCESS | 32453 | |5;yui.html;844.5059640110536;857.2713193785281;868.7393781865443;894.5080205886765;935.8081593273371;16873.746893796586;18253.904765714065;18458.907705461454;18486.537836554075;18510;15421.15183664898;15520.767188492182;16060.598485037879;16583.253753581088;16804.74792878108;889.8309321228978;909.0999910088997;910.4901255297021;911.3347256502724;911.5852287209337;738.8152961759561;879.2394578689419;895.2882458445551;896.8789213456165;904.7738065483629;956.7703751099721;959.4483172175992;960.1838437328284;960.5581432541005;961.5048250151178;952.5570609666538;954.7660823098386;955.0592666223764;955.0926619711267;955.2835373469898;574.029889486766;585.7247093865918;672.9899051514232;706.6643344411415;707.119636053112;15804.762928556082;16940.407085752064;16986.15069246545;17013.319467221296;17016.914915425343;15805.446809361618;16127.83872161275;16159.515214543464;16241.106739129344;16361.727654469154;13148.934255328757;13165.934170329183;13291.667708307274;13480;13647.38586763598;4686.929696054562;4692.296155576691;4745.311929770164;4772.188727916263;4804.807807687724;87111;87802;89013.55493222557;90254.5487272566;90578.54710726404;11609.30344179352;11652.592159274385;11757.294562326288;11830.704232394259;11917.046636269077;24689.135880244463;24692;24695;24703;24703.3824154394;9461.716148515556;9640.084192001748;9677.612895484242;9727.65953191649;9832;5142.357205349332;5165.612579056586;5194.831023801328;5305.522502974751;5412.134058550691;3830.751001184914;3990.640842324147;3992.6007399260425;4000.9597504649;4006.1787333596544;645.6771614192903;652.5823472977298;653.7515744017243;657.0964923230558;694.350782018814;620.9161763162006;636.9999101410833;741.9183889772121;747.5813544415145;747.9289467500579;10093.545790439448;10433.478326083665;10567.89432105669;10788.838167427495;10817.37561406032;614.7756069034859;665.7137430904758;712.262807993724;769.9345555627834;792.5720111140035;4831.855044348676;4850.53919877611;4894.461609222983;4909.705417674951;4939.75301234944;122045.38977305057;124287;127157.36421317834;129832.35083824709;134124.32937835442;65001.34998650048;65540.67229663821;100585;103380;108951.45524272328;57228.42771572231;57883;68614.65692671505;103588;106743.46628266809;9336.579853906725;9651.517424128906;9657.855132173163;9663;9687.564059617192;44827.327590086825;51997;52761.7361913188;53265.73367133294;59586.10620840778;49490.010199796525;51111.48888511216;66624.6668766653;69073;76651;14176.6359091698;14460.2046887418;14544.781828272373;14601.612846779539;14793.92603036978;14577.344019518934;15047.623809404853;15626.062096403293;16125.516234512983;17238.310467581658;15249.3900243989;15530.84469155294;16453.753193701865;17083.231254593822;17582.472525824247;15232.116469806266;15318.540443786704;15599.610009749847;15951.282192301142;16149.19254037317;15170.772438413213;15263.819095477511;15336.693266134838;15533.611659708598;15640.765388519409;10867.574454530824;11517.308961462339;11773;12301.876981230072;12317.260964342167;11604.6518604442;11616;11649.534018639159;11670.533178672758;11764.647060588197 04:32:25 INFO - PROCESS | 32453 | __end_tp_report 04:32:25 INFO - PROCESS | 32453 | __start_cc_report 04:32:25 INFO - PROCESS | 32453 | _x_x_mozilla_cycle_collect,1074 04:32:25 INFO - PROCESS | 32453 | __end_cc_report 04:32:25 INFO - PROCESS | 32453 | __startTimestamp1471519945297__endTimestamp 04:32:25 INFO - PROCESS | 32453 | 04:32:25 INFO - PROCESS | 32453 | ------- Summary: start ------- 04:32:25 INFO - PROCESS | 32453 | Number of tests: 6 04:32:25 INFO - PROCESS | 32453 | 04:32:25 INFO - PROCESS | 32453 | [#0] dojo.html Cycles:165 Average:5604.88 Median:5828.85 stddev:648.11 (11.1%) stddev-sans-first:622.40 04:32:25 INFO - PROCESS | 32453 | Values: 3215.6 3241.4 3247.0 3270.3 3303.5 5615.9 5714.8 5724.3 5725.3 5747.1 5577.2 5627.7 5634.8 5636.4 5672.1 4969.7 5013.9 5037.1 5078.9 5121.7 5076.3 5130.5 5133.5 5139.4 5139.9 5032.5 5063.0 5078.5 5078.7 5136.0 5180.5 5182.3 5194.2 5202.2 5221.5 4920.7 4990.0 5008.8 5065.2 5071.1 5489.2 5502.7 5515.0 5575.4 5575.6 6025.7 6049.1 6054.0 6059.0 6063.2 6026.9 6061.5 6066.1 6091.8 6101.9 5676.1 5719.2 5728.0 5729.3 5729.8 5850.4 6208.3 6209.3 6209.8 6213.6 5816.9 5881.6 5891.3 5989.3 6008.6 6035.9 6041.2 6067.3 6076.2 6082.7 5784.0 6010.7 6024.3 6026.4 6026.4 5928.6 5993.3 6014.2 6062.4 6113.6 4642.0 4642.7 4643.3 4646.8 4666.5 4000.0 4153.3 4173.8 4178.0 4191.6 5630.0 5634.6 5665.5 5711.0 5747.5 6040.8 6089.4 6093.2 6101.3 6120.5 5572.3 5715.7 5718.3 5724.0 5729.5 5056.1 5085.5 5090.2 5140.1 5158.0 6326.3 6336.4 6336.7 6338.3 6344.3 5860.3 6123.0 6136.5 6138.2 6147.4 6175.2 6250.9 6251.5 6285.0 6334.2 5932.1 6003.3 6007.7 6009.2 6027.9 5889.2 6092.3 6097.9 6101.8 6104.3 5804.8 5865.0 5870.0 5922.8 5943.3 5894.3 5897.9 5900.1 5903.1 5904.6 5647.5 5828.9 5872.5 5879.0 5881.2 5845.1 5880.4 5902.2 5989.7 5993.7 5815.4 5828.4 5828.5 5834.2 5834.9 04:32:25 INFO - PROCESS | 32453 | 04:32:25 INFO - PROCESS | 32453 | [#1] ext.html Cycles:180 Average:70151.44 Median:43480.45 stddev:140891.67 (324.0%) stddev-sans-first:141190.15 04:32:25 INFO - PROCESS | 32453 | Values: 606.3 645.8 664.2 683.9 689.2 36910.8 48556.8 49759.5 49972.0 49998.5 49012.8 49431.8 49690.3 49723.5 49726.3 8536.5 9376.5 9522.9 9539.1 9540.4 11042.5 11322.9 11334.7 11705.6 11712.6 11534.4 11619.2 11637.7 11642.0 11644.4 11275.3 11297.2 11340.7 11350.9 11363.9 11247.7 11346.0 11349.7 11354.6 11355.4 47059.0 47144.3 47384.8 47398.5 47401.8 3891.1 3944.4 4648.9 4782.9 4801.8 35202.3 35751.0 35789.5 35893.1 37451.3 4740.9 4748.1 4813.2 4813.4 4867.4 391674.0 391768.0 394420.0 395327.0 395483.0 69019.7 69398.7 69573.9 69692.3 69711.3 40805.4 41646.6 41683.0 73067.6 73200.6 18512.7 18615.8 18782.6 18792.4 18816.2 12959.2 14189.9 14195.6 14992.5 15515.1 21659.7 21791.9 21827.3 21836.9 21871.9 7654.3 7672.7 7704.9 7720.9 7754.2 1075.9 1086.3 1089.2 1171.1 1201.3 35524.5 35998.8 36286.1 36297.3 36298.3 1138.3 1145.6 1159.2 1178.5 1238.2 17296.0 18391.5 18400.4 18427.3 18430.7 652631.0 654955.0 655304.0 655517.0 661595.0 66067.7 66705.3 66721.3 66833.3 66954.0 1352.4 1476.1 1478.8 1487.6 1489.4 34564.7 37036.4 37205.0 37402.0 37454.6 56978.1 57496.4 57516.7 57676.1 57711.4 490748.2 494026.0 496569.0 497049.0 562380.0 46683.3 49060.8 49272.8 49418.3 50177.5 54549.0 54748.7 54977.5 55634.0 56480.0 45276.0 47605.5 47994.8 48038.8 49198.0 45196.3 45361.5 46443.0 47403.8 48068.3 44820.1 47015.0 47868.0 48333.3 48460.8 38481.0 41264.0 41404.2 41478.0 42140.8 30660.8 30998.8 31121.2 31683.8 31796.4 04:32:25 INFO - PROCESS | 32453 | 04:32:25 INFO - PROCESS | 32453 | [#2] jquery.html Cycles:180 Average:29904.14 Median:2599.57 stddev:81089.71 (3119.4%) stddev-sans-first:81286.92 04:32:25 INFO - PROCESS | 32453 | Values: 397.1 409.5 426.5 431.7 441.4 15928.4 21481.7 21672.7 21699.1 21983.2 20827.1 21051.8 21086.2 21154.5 21315.1 5573.8 5932.4 5983.5 5987.5 5992.7 5715.8 5896.1 5961.1 5977.1 5977.4 5812.8 5835.2 5838.6 5841.4 5849.9 5614.6 5744.3 5818.9 5834.7 5838.9 5202.3 5250.9 5270.8 5273.2 5274.8 24395.1 24427.5 24822.9 24953.0 25129.6 2494.0 2551.6 2569.9 2629.2 3205.9 15275.5 15474.0 15504.4 16034.0 16281.8 2036.4 2050.5 2066.9 2074.0 2109.7 67747.3 70036.7 71009.0 86347.6 87569.0 9352.8 11408.2 11635.6 11719.4 11750.1 13213.0 13891.0 13920.4 14267.6 14293.6 8367.4 8377.3 8482.7 8539.8 8587.5 8546.3 8645.5 8658.1 8799.1 8810.2 4378.0 4397.7 4417.5 4495.3 4569.3 2086.4 2119.2 2127.0 2132.8 2173.0 930.3 986.9 994.3 1010.1 1076.8 20884.0 21037.2 21489.5 21521.7 21717.3 1029.9 1034.3 1062.1 1064.6 1095.7 11651.5 11824.8 12054.4 12068.8 12087.3 478123.0 485735.0 485959.0 503224.0 503683.0 96172.5 96365.0 96411.5 96863.0 98377.0 1014.6 1070.7 1096.9 1110.3 1121.1 18791.4 19342.4 19448.4 19561.2 19608.8 45062.3 45623.0 46345.8 47209.0 47927.3 64543.0 64849.7 65193.7 66537.0 66932.7 9023.4 9351.5 9354.2 9400.7 9585.8 11918.7 13594.2 13799.4 14233.1 14558.8 12303.3 12303.6 12419.3 12504.0 12715.9 12054.9 12260.3 12337.9 12605.9 12759.7 6230.5 8003.9 12455.6 12739.9 12886.7 9218.5 9234.2 9296.2 9357.9 9616.2 9356.6 11524.9 12032.9 12401.5 12820.7 04:32:25 INFO - PROCESS | 32453 | 04:32:25 INFO - PROCESS | 32453 | [#3] mootools.html Cycles:180 Average:6157.35 Median:2658.13 stddev:3520.01 (132.4%) stddev-sans-first:3512.94 04:32:25 INFO - PROCESS | 32453 | Values: 1560.6 1606.1 1801.6 1872.8 1916.8 8621.0 9276.5 9279.8 9281.1 9284.0 8283.9 8558.1 8944.6 9001.1 9023.3 2386.9 2444.8 2447.2 2448.6 2451.2 2413.3 2460.6 2464.8 2481.5 2481.6 2165.4 2231.5 2231.9 2232.5 2388.7 2307.7 2373.1 2380.7 2383.2 2384.1 2423.0 2426.8 2431.7 2450.8 2453.7 9061.6 9188.5 9214.9 9218.1 9223.1 5580.5 5740.6 5745.1 5754.6 5756.9 3376.6 3467.6 3523.4 3526.9 3539.7 1191.7 1229.3 1231.1 1246.2 1255.0 12368.1 12688.7 12688.8 12720.8 12785.0 4832.8 4891.7 4927.3 4958.6 5031.3 11151.9 11153.1 11153.2 11158.2 11165.4 5600.8 5611.4 5615.2 5622.5 5622.9 5078.3 5114.2 5134.0 5193.2 5207.0 4320.8 4335.5 4356.1 4412.7 4432.9 1369.2 1416.0 1419.1 1428.6 1436.9 1033.4 1036.3 1044.5 1045.1 1048.8 6326.3 6332.9 6347.9 6468.1 6517.9 483.5 486.5 489.3 490.2 504.1 4283.5 4318.4 4444.3 4461.4 4550.8 11712.0 12046.3 12074.5 12104.0 12110.4 10939.5 11620.8 11654.8 11806.2 12015.2 8519.7 8728.2 8735.8 8742.0 8745.4 6534.5 6545.2 6562.6 6625.2 6807.9 10569.2 10776.6 10807.1 10832.9 10952.7 11205.6 11220.6 11252.5 11259.7 11310.8 8029.2 8162.0 8167.2 8209.9 8433.8 8871.4 9035.1 9036.1 9039.3 9045.1 7935.2 8063.3 8175.0 8227.2 8377.9 7571.3 7648.6 7665.3 7695.5 7770.7 7500.7 7575.3 7607.9 7609.6 7610.8 5611.1 5637.1 5653.0 5674.0 5816.4 5449.1 5493.0 5564.6 5609.4 5691.0 04:32:25 INFO - PROCESS | 32453 | 04:32:25 INFO - PROCESS | 32453 | [#4] prototype.html Cycles:180 Average:4856.78 Median:5488.81 stddev:1417.97 (25.8%) stddev-sans-first:1380.90 04:32:25 INFO - PROCESS | 32453 | Values: 343.6 345.9 351.3 358.9 360.0 5591.0 5621.8 5670.0 5674.9 5679.1 5474.7 5502.9 5587.2 5588.6 5594.0 3845.6 4109.7 4242.5 4281.7 4288.5 4295.9 4301.3 4303.5 4305.0 4323.8 4294.0 4300.2 4304.4 4321.5 4331.5 4134.0 4230.6 4321.8 4330.2 4336.4 3805.8 3813.8 3818.7 3829.3 3862.2 5375.2 5400.2 5423.2 5427.6 5444.2 4766.7 4915.8 4948.7 4952.7 4988.7 5692.2 5710.7 5724.1 5737.5 5756.5 4214.9 4217.7 4220.2 4222.5 4253.4 5691.7 5853.4 5858.8 6041.4 6092.9 4290.2 4331.5 4341.2 4351.6 4361.0 4392.1 4401.3 4405.6 4423.8 4425.4 4901.9 4989.2 5007.2 5009.0 5027.5 5749.4 5794.3 5795.7 5833.7 5846.2 1508.4 1531.9 1532.8 1532.9 1536.9 943.8 979.4 986.1 999.0 1016.6 4876.6 4958.7 4973.4 4990.0 4998.2 5218.3 5237.6 5265.6 5302.9 5317.8 4993.2 5016.4 5076.5 5245.6 5646.8 3141.9 3176.0 3187.8 3189.3 3190.0 6066.7 6196.6 6198.8 6230.8 6248.6 6016.1 6046.2 6070.4 6073.0 6090.5 6185.9 6188.6 6195.4 6197.7 6226.2 5842.3 5865.5 5906.3 5940.8 6011.6 5857.9 5881.3 5911.8 5930.5 5932.4 5832.6 5880.6 6018.5 6035.3 6161.2 4144.9 4281.0 4296.7 4301.0 4318.7 5985.8 5998.8 6008.9 6035.7 6048.7 5603.0 5788.2 5792.5 5855.2 5888.9 5795.5 5833.5 5859.0 5861.7 5867.6 5808.4 5830.7 5833.9 5869.8 5872.9 5737.1 5843.3 5862.9 5868.2 5901.8 5728.4 5744.3 5747.7 5748.3 5761.8 04:32:25 INFO - PROCESS | 32453 | 04:32:25 INFO - PROCESS | 32453 | [#5] yui.html Cycles:180 Average:21469.56 Median:24757.13 stddev:30480.62 (123.1%) stddev-sans-first:30526.78 04:32:25 INFO - PROCESS | 32453 | Values: 844.5 857.3 868.7 894.5 935.8 16873.7 18253.9 18458.9 18486.5 18510.0 15421.2 15520.8 16060.6 16583.3 16804.7 889.8 909.1 910.5 911.3 911.6 738.8 879.2 895.3 896.9 904.8 956.8 959.4 960.2 960.6 961.5 952.6 954.8 955.1 955.1 955.3 574.0 585.7 673.0 706.7 707.1 15804.8 16940.4 16986.2 17013.3 17016.9 15805.4 16127.8 16159.5 16241.1 16361.7 13148.9 13165.9 13291.7 13480.0 13647.4 4686.9 4692.3 4745.3 4772.2 4804.8 87111.0 87802.0 89013.6 90254.5 90578.5 11609.3 11652.6 11757.3 11830.7 11917.0 24689.1 24692.0 24695.0 24703.0 24703.4 9461.7 9640.1 9677.6 9727.7 9832.0 5142.4 5165.6 5194.8 5305.5 5412.1 3830.8 3990.6 3992.6 4001.0 4006.2 645.7 652.6 653.8 657.1 694.4 620.9 637.0 741.9 747.6 747.9 10093.5 10433.5 10567.9 10788.8 10817.4 614.8 665.7 712.3 769.9 792.6 4831.9 4850.5 4894.5 4909.7 4939.8 122045.4 124287.0 127157.4 129832.4 134124.3 65001.3 65540.7 100585.0 103380.0 108951.5 57228.4 57883.0 68614.7 103588.0 106743.5 9336.6 9651.5 9657.9 9663.0 9687.6 44827.3 51997.0 52761.7 53265.7 59586.1 49490.0 51111.5 66624.7 69073.0 76651.0 14176.6 14460.2 14544.8 14601.6 14793.9 14577.3 15047.6 15626.1 16125.5 17238.3 15249.4 15530.8 16453.8 17083.2 17582.5 15232.1 15318.5 15599.6 15951.3 16149.2 15170.8 15263.8 15336.7 15533.6 15640.8 10867.6 11517.3 11773.0 12301.9 12317.3 11604.7 11616.0 11649.5 11670.5 11764.6 04:32:25 INFO - PROCESS | 32453 | -------- Summary: end -------- 04:32:25 INFO - PROCESS | 32453 | 04:32:26 INFO - TEST-INFO | 32453: exit 0 04:32:26 INFO - TEST-OK | dromaeo_css | took 1114270ms 04:32:26 INFO - TEST-START | kraken 04:32:26 INFO - operating with platform_type : linux_ 04:32:26 INFO - Initialising browser for kraken test... 04:32:26 INFO - TEST-INFO | started process 32660 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp11u12X/profile http://localhost:59629/getInfo.html) 04:32:30 INFO - PROCESS | 32660 | __metrics Screen width/height:1600/1200 04:32:30 INFO - PROCESS | 32660 | colorDepth:24 04:32:30 INFO - PROCESS | 32660 | Browser inner width/height: 1024/697 04:32:30 INFO - PROCESS | 32660 | __metrics 04:32:31 INFO - PROCESS | 32660 | 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:32:31 INFO - PROCESS | 32660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:32:32 INFO - TEST-INFO | 32660: exit 0 04:32:32 INFO - Browser initialized. 04:32:32 INFO - Running cycle 1/1 for kraken test... 04:32:32 INFO - TEST-INFO | started process 32751 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp11u12X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:44 INFO - PROCESS | 32751 | RSS: Main: 146067456 04:32:44 INFO - PROCESS | 32751 | 04:32:47 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59629/tests/kraken/driver.html?testName=audio-beat-detection) 04:32:47 INFO - PROCESS | 32751 | RSS: Main: 191692800 04:32:47 INFO - PROCESS | 32751 | 04:32:50 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59629/tests/kraken/driver.html?testName=audio-dft) 04:32:51 INFO - PROCESS | 32751 | RSS: Main: 181833728 04:32:51 INFO - PROCESS | 32751 | 04:32:53 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59629/tests/kraken/driver.html?testName=audio-fft) 04:32:54 INFO - PROCESS | 32751 | RSS: Main: 270905344 04:32:54 INFO - PROCESS | 32751 | 04:32:56 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59629/tests/kraken/driver.html?testName=audio-oscillator) 04:32:57 INFO - PROCESS | 32751 | RSS: Main: 175632384 04:32:57 INFO - PROCESS | 32751 | 04:32:59 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59629/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:32:59 INFO - PROCESS | 32751 | RSS: Main: 177217536 04:32:59 INFO - PROCESS | 32751 | 04:33:02 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59629/tests/kraken/driver.html?testName=imaging-darkroom) 04:33:03 INFO - PROCESS | 32751 | RSS: Main: 213086208 04:33:03 INFO - PROCESS | 32751 | 04:33:06 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59629/tests/kraken/driver.html?testName=imaging-desaturate) 04:33:07 INFO - PROCESS | 32751 | RSS: Main: 211857408 04:33:07 INFO - PROCESS | 32751 | 04:33:10 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59629/tests/kraken/driver.html?testName=json-parse-financial) 04:33:10 INFO - PROCESS | 32751 | RSS: Main: 211206144 04:33:10 INFO - PROCESS | 32751 | 04:33:12 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59629/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:33:12 INFO - PROCESS | 32751 | RSS: Main: 175800320 04:33:12 INFO - PROCESS | 32751 | 04:33:14 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59629/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:33:15 INFO - PROCESS | 32751 | RSS: Main: 181366784 04:33:15 INFO - PROCESS | 32751 | 04:33:17 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59629/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:33:17 INFO - PROCESS | 32751 | RSS: Main: 186388480 04:33:17 INFO - PROCESS | 32751 | 04:33:20 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59629/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:33:20 INFO - PROCESS | 32751 | RSS: Main: 186961920 04:33:20 INFO - PROCESS | 32751 | 04:33:23 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59629/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:33:24 INFO - PROCESS | 32751 | RSS: Main: 184688640 04:33:24 INFO - PROCESS | 32751 | 04:33:25 INFO - PROCESS | 32751 | Cycle 1(1): loaded http://localhost:59629/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59629/tests/kraken/driver.html?testName=ai-astar) 04:33:26 INFO - PROCESS | 32751 | RSS: Main: 235655168 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | __start_tp_report 04:33:26 INFO - PROCESS | 32751 | _x_x_mozilla_page_load 04:33:26 INFO - PROCESS | 32751 | _x_x_mozilla_page_load_details 04:33:26 INFO - PROCESS | 32751 | |i|pagename|runs| 04:33:26 INFO - PROCESS | 32751 | |0;ai-astar;100;111;112;111;110;112;111;109;112;110 04:33:26 INFO - PROCESS | 32751 | |1;audio-beat-detection;147;115;120;173;117;118;141;116;162;118 04:33:26 INFO - PROCESS | 32751 | |2;audio-dft;161;161;163;161;159;162;163;161;164;160 04:33:26 INFO - PROCESS | 32751 | |3;audio-fft;74;76;74;112;75;76;77;76;100;75 04:33:26 INFO - PROCESS | 32751 | |4;audio-oscillator;83;83;128;83;83;83;120;84;83;130 04:33:26 INFO - PROCESS | 32751 | |5;imaging-gaussian-blur;117;116;117;117;116;117;118;117;116;117 04:33:26 INFO - PROCESS | 32751 | |6;imaging-darkroom;146;146;146;146;146;145;146;147;146;145 04:33:26 INFO - PROCESS | 32751 | |7;imaging-desaturate;95;95;95;94;95;94;94;95;95;94 04:33:26 INFO - PROCESS | 32751 | |8;json-parse-financial;51;51;51;51;50;52;53;52;51;49 04:33:26 INFO - PROCESS | 32751 | |9;json-stringify-tinderbox;60;61;60;60;59;58;59;60;60;61 04:33:26 INFO - PROCESS | 32751 | |10;stanford-crypto-aes;73;72;72;82;72;73;72;72;82;78 04:33:26 INFO - PROCESS | 32751 | |11;stanford-crypto-ccm;122;117;117;117;115;116;117;124;148;120 04:33:26 INFO - PROCESS | 32751 | |12;stanford-crypto-pbkdf2;152;157;150;159;155;151;154;150;151;147 04:33:26 INFO - PROCESS | 32751 | |13;stanford-crypto-sha256-iterative;61;64;63;61;61;61;65;63;60;60 04:33:26 INFO - PROCESS | 32751 | __end_tp_report 04:33:26 INFO - PROCESS | 32751 | __start_cc_report 04:33:26 INFO - PROCESS | 32751 | _x_x_mozilla_cycle_collect,1634 04:33:26 INFO - PROCESS | 32751 | __end_cc_report 04:33:26 INFO - PROCESS | 32751 | __startTimestamp1471520006020__endTimestamp 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | ------- Summary: start ------- 04:33:26 INFO - PROCESS | 32751 | Number of tests: 14 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#0] ai-astar Cycles:10 Average:109.80 Median:111.00 stddev:3.58 (3.2%) stddev-sans-first:1.05 04:33:26 INFO - PROCESS | 32751 | Values: 100.0 111.0 112.0 111.0 110.0 112.0 111.0 109.0 112.0 110.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:130.50 stddev:21.57 (16.5%) stddev-sans-first:22.25 04:33:26 INFO - PROCESS | 32751 | Values: 147.0 115.0 120.0 173.0 117.0 118.0 141.0 116.0 162.0 118.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:1.51 (0.9%) stddev-sans-first:1.59 04:33:26 INFO - PROCESS | 32751 | Values: 161.0 161.0 163.0 161.0 159.0 162.0 163.0 161.0 164.0 160.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.50 stddev:13.25 (17.6%) stddev-sans-first:13.77 04:33:26 INFO - PROCESS | 32751 | Values: 74.0 76.0 74.0 112.0 75.0 76.0 77.0 76.0 100.0 75.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:83.00 stddev:20.85 (25.1%) stddev-sans-first:21.58 04:33:26 INFO - PROCESS | 32751 | Values: 83.0 83.0 128.0 83.0 83.0 83.0 120.0 84.0 83.0 130.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 04:33:26 INFO - PROCESS | 32751 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 118.0 117.0 116.0 117.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 04:33:26 INFO - PROCESS | 32751 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 146.0 147.0 146.0 145.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 04:33:26 INFO - PROCESS | 32751 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 94.0 95.0 95.0 94.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:1.10 (2.2%) stddev-sans-first:1.17 04:33:26 INFO - PROCESS | 32751 | Values: 51.0 51.0 51.0 51.0 50.0 52.0 53.0 52.0 51.0 49.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 04:33:26 INFO - PROCESS | 32751 | Values: 60.0 61.0 60.0 60.0 59.0 58.0 59.0 60.0 60.0 61.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:73.00 stddev:4.21 (5.8%) stddev-sans-first:4.42 04:33:26 INFO - PROCESS | 32751 | Values: 73.0 72.0 72.0 82.0 72.0 73.0 72.0 72.0 82.0 78.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#11] stanford-crypto-ccm Cycles:10 Average:121.30 Median:118.50 stddev:9.80 (8.3%) stddev-sans-first:10.39 04:33:26 INFO - PROCESS | 32751 | Values: 122.0 117.0 117.0 117.0 115.0 116.0 117.0 124.0 148.0 120.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.60 Median:153.00 stddev:3.63 (2.4%) stddev-sans-first:3.84 04:33:26 INFO - PROCESS | 32751 | Values: 152.0 157.0 150.0 159.0 155.0 151.0 154.0 150.0 151.0 147.0 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - PROCESS | 32751 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.73 (2.8%) stddev-sans-first:1.80 04:33:26 INFO - PROCESS | 32751 | Values: 61.0 64.0 63.0 61.0 61.0 61.0 65.0 63.0 60.0 60.0 04:33:26 INFO - PROCESS | 32751 | -------- Summary: end -------- 04:33:26 INFO - PROCESS | 32751 | 04:33:26 INFO - TEST-INFO | 32751: exit 0 04:33:26 INFO - TEST-OK | kraken | took 60834ms 04:33:26 INFO - SUITE-END | took 1175s 04:33:27 INFO - Completed test suite (00:19:35) 04:33:27 INFO - javascript benchmark 04:33:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3215.6462789093202, 3241.4165450218966, 3246.9837650811755, 3270.3132342208137, 3303.454929936559, 5615.859603509909, 5714.828555143342, 5724.34170070442, 5725.312962444502, 5747.109198074295, 5577.163425486179, 5627.7467513961865, 5634.830955071344, 5636.379998200195, 5672.120821272699, 4969.652124351297, 5013.924791128136, 5037.093323201813, 5078.923816142761, 5121.718305493197, 5076.314697515825, 5130.538251557359, 5133.512316329943, 5139.408967968694, 5139.897202055957, 5032.4967503249745, 5063.012712521041, 5078.542931136197, 5078.695278283296, 5136.0, 5180.507851754077, 5182.300389447433, 5194.246834209019, 5202.193659982709, 5221.477852214767, 4920.655554111214, 4989.950100498985, 5008.774605142778, 5065.1895696688725, 5071.112555302844, 5489.176623506466, 5502.697351645658, 5515.034868897927, 5575.358833734132, 5575.581831362664, 6025.6987150642735, 6049.122877182828, 6054.0, 6059.030555111205, 6063.18147050147, 6026.9095963560585, 6061.51507879373, 6066.090086487018, 6091.817245482598, 6101.908471372933, 5676.120201368794, 5719.227904232917, 5728.0, 5729.28383952006, 5729.828105156852, 5850.444207800252, 6208.286047104551, 6209.254889413209, 6209.844753881099, 6213.565050446426, 5816.94183058164, 5881.558883083786, 5891.263592050994, 5989.2812862456785, 6008.609440386448, 6035.879282414415, 6041.184440100618, 6067.302260240041, 6076.179715738407, 6082.726277317532, 5784.045632470679, 6010.699465026732, 6024.337322894478, 6026.367231440723, 6026.367231440723, 5928.555358348141, 5993.250843644544, 6014.1580178775, 6062.363451837582, 6113.57204995655, 4642.048380082075, 4642.744649044335, 4643.349931009661, 4646.767661616906, 4666.4633567139535, 4000.0399904023407, 4153.252414565347, 4173.791310434466, 4178.039051018282, 4191.580841915784, 5630.0428927081675, 5634.6337488063145, 5665.5184309333345, 5711.0, 5747.5114615257335, 6040.7583696651645, 6089.42150495711, 6093.2078829751845, 6101.267847858197, 6120.510359171256, 5572.331320241516, 5715.685637289906, 5718.256626638594, 5724.0, 5729.455701708331, 5056.140456122468, 5085.516875896784, 5090.211017292326, 5140.074786538385, 5158.0199762045095, 6326.272478665012, 6336.429721325103, 6336.714847831859, 6338.302786693553, 6344.302126766052, 5860.267466566679, 6122.989706698345, 6136.478399335928, 6138.202033735586, 6147.385261473817, 6175.166352542529, 6250.906236406367, 6251.531135164972, 6284.968575157095, 6334.239891213084, 5932.139839723302, 6003.339632640494, 6007.699615019319, 6009.248843894513, 6027.879442411039, 5889.234399528034, 6092.268927728701, 6097.908531372122, 6101.7559297627595, 6104.32852386246, 5804.82585522435, 5864.970675146597, 5870.031444811559, 5922.7927022554, 5943.257092863392, 5894.2926848778425, 5897.882042359214, 5900.1149827526215, 5903.085021821625, 5904.645721256738, 5647.5481961442165, 5828.854278642898, 5872.500837428866, 5879.0, 5881.235439392852, 5845.09401042839, 5880.353161152184, 5902.2327097477055, 5989.7304621293015, 5993.670348130895, 5815.389384114607, 5828.417158284137, 5828.533717302521, 5834.241548598655, 5834.854128646648], "value": 5560.581141909761, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.2906399512573, 645.8223988403189, 664.2328111031757, 683.8700646877093, 689.2315068698402, 36910.815445922766, 48556.75721621391, 49759.50240497594, 49971.99999999991, 49998.50001499984, 49012.754936225356, 49431.75284123574, 49690.254646180336, 49723.50276497243, 49726.25410618843, 8536.530490823003, 9376.484293363845, 9522.904770952271, 9539.141477267045, 9540.379875308117, 11042.503087361089, 11322.886771132265, 11334.659960201167, 11705.59030433935, 11712.648620541355, 11534.423278836024, 11619.244749091324, 11637.650870473899, 11642.0, 11644.359560224184, 11275.267107637977, 11297.209195356287, 11340.716482088013, 11350.943245283803, 11363.886361136407, 11247.66257012291, 11345.978861902386, 11349.659510214708, 11354.60258890943, 11355.43222838863, 47059.0, 47144.29283560715, 47384.76307618475, 47398.52601473976, 47401.76299118482, 3891.1439483313625, 3944.3886197639413, 4648.907021859578, 4782.856514304576, 4801.783919723586, 35202.29595408103, 35751.0, 35789.46315805265, 35893.10267243314, 37451.25097498035, 4740.907933305685, 4748.07412554552, 4813.205821039524, 4813.350197723297, 4867.440244371872, 391674.0, 391768.0, 394420.0, 395327.0, 395483.0, 69019.65490172517, 69398.65300673465, 69573.8691758872, 69692.30307696959, 69711.30288697049, 40805.38791918124, 41646.58353416427, 41683.0, 73067.63466182741, 73200.63399683074, 18512.722309165372, 18615.81384186168, 18782.624347513247, 18792.43622691294, 18816.24735010584, 12959.222446653232, 14189.85810141906, 14195.645108872155, 14992.47526336573, 15515.146666933428, 21659.67510487344, 21791.891040544695, 21827.345179644635, 21836.89081554614, 21871.890640546695, 7654.311111999947, 7672.693092276247, 7704.922950770532, 7720.884186737204, 7754.224577542201, 1075.9246852720391, 1086.3481910853425, 1089.2048804372694, 1171.1158075652827, 1201.327256736213, 35524.46713299303, 35998.82000590033, 36286.09284767902, 36297.27405451876, 36298.27403451916, 1138.3226979947083, 1145.627671006926, 1159.191661008829, 1178.505027888289, 1238.2322959765004, 17296.0, 18391.540211494554, 18400.447986560426, 18427.26290948347, 18430.723539146922, 652631.0, 654955.0, 655304.0, 655517.0, 661595.0, 66067.66966165138, 66705.3329466699, 66721.33278667249, 66833.33166668368, 66954.0, 1352.3981828086407, 1476.0995792566741, 1478.8299345575397, 1487.6132205626616, 1489.3967942983109, 34564.65435345614, 37036.44445333268, 37205.0, 37402.0, 37454.62545374512, 56978.145327819286, 57496.425035749104, 57516.71241643765, 57676.134857978, 57711.422885770604, 490748.18503629015, 494026.0, 496569.0, 497049.0, 562380.0, 46683.29975050445, 49060.75469622629, 49272.75363623159, 49418.258726118416, 50177.49822501728, 54549.0, 54748.72625636846, 54977.45022549723, 55634.0, 56480.0, 45276.0, 47605.523944761495, 47994.76002619965, 48038.759806200746, 49198.0, 45196.28408750348, 45361.546384537054, 46443.0, 47403.762981184875, 48068.27897581469, 44820.10359792851, 47015.0, 47868.0, 48333.27500087431, 48460.757696211294, 38481.0, 41264.0, 41404.171916560896, 41478.0, 42140.78929605332, 30660.846695766377, 30998.84500577573, 31121.221969450944, 31683.84158079195, 31796.36407271888], "value": 23113.814590723337, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.08471972104286, 409.4615580511625, 426.48821414302836, 431.6740860650208, 441.4283502863791, 15928.362865485382, 21481.67777483335, 21672.674909876343, 21699.132034718612, 21983.23058692942, 20827.062782174802, 21051.789482105174, 21086.15655373787, 21154.471138221514, 21315.147394104217, 5573.832785016456, 5932.377100404438, 5983.521318294526, 5987.461128498434, 5992.670403127826, 5715.77136914525, 5896.056630939051, 5961.105834124873, 5977.073553599199, 5977.372375900533, 5812.825615231529, 5835.183074369592, 5838.591298609099, 5841.357450680422, 5849.8830023399505, 5614.606977511576, 5744.310682718101, 5818.883622327572, 5834.679092649882, 5838.883222335551, 5202.271681964528, 5250.894982100374, 5270.841874743764, 5273.235380869791, 5274.789008439658, 24395.14616988397, 24427.511449770904, 24822.875885620637, 24953.0, 25129.62305565418, 2494.0, 2551.59174532074, 2569.897204111833, 2629.2375211188732, 3205.9038228853174, 15275.541733748005, 15474.0, 15504.379824806994, 16034.0, 16281.83718162815, 2036.3992622176493, 2050.456628993318, 2066.90698918549, 2073.9896300518553, 2109.6519074352714, 67747.32252677459, 70036.74091185156, 71009.0, 86347.56826215828, 87569.0, 9352.766180845398, 11408.201425900274, 11635.603727552578, 11719.355435450962, 11750.059995200363, 13213.0, 13891.0, 13920.373583188579, 14267.571972840831, 14293.57119286423, 8367.406104107746, 8377.287930525852, 8482.660693572188, 8539.829203415897, 8587.484750914966, 8546.273566746897, 8645.481271123752, 8658.134186581417, 8799.120087991278, 8810.207081362709, 4377.956220437819, 4397.670174736908, 4417.536158703354, 4495.348174514677, 4569.314602809605, 2086.4262327859656, 2119.1841141160457, 2126.989365053165, 2132.758207524554, 2173.0330003148447, 930.265090578449, 986.9062439068277, 994.269212129099, 1010.1312870930993, 1076.806174888512, 20884.0, 21037.15851365959, 21489.46276343104, 21521.688077428647, 21717.34847954564, 1029.907308342238, 1034.2760067952463, 1062.0759938853093, 1064.5688496159069, 1095.7205912492423, 11651.53393864253, 11824.763504729857, 12054.397280136134, 12068.818967715491, 12087.274763514217, 478123.0, 485735.0, 485959.0, 503224.0, 503683.0, 96172.51913740387, 96365.0, 96411.51794240985, 96863.0, 98377.0, 1014.599233302856, 1070.6948519671855, 1096.873859506167, 1110.294962698676, 1121.1311233793874, 18791.436256912315, 19342.4197274082, 19448.4165475036, 19561.21755129779, 19608.8039119607, 45062.32406513971, 45623.0, 46345.76827115843, 47209.0, 47927.28109078297, 64543.0, 64849.67575162094, 65193.67403162954, 66537.0, 66932.665336673, 9023.368364214704, 9351.48566828831, 9354.158125768714, 9400.670976515788, 9585.808283834145, 11918.702032449259, 13594.184348939096, 13799.379027943565, 14233.146011239358, 14558.781618275947, 12303.261804291771, 12303.630891073282, 12419.254844709347, 12504.0, 12715.934484320465, 12054.879451205376, 12260.32568208757, 12337.876621233672, 12605.93697031509, 12759.744805104032, 6230.532710046674, 8003.919960800317, 12455.567609724998, 12739.872601274239, 12886.67783305425, 9218.539073046455, 9234.168924796531, 9296.209822165192, 9357.906420935977, 9616.182624476718, 9356.578953946953, 11524.94237528807, 12032.879671203526, 12401.503939842305, 12820.743585128432], "value": 9727.984021242872, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1560.6176486760742, 1606.1166358502821, 1801.6486785076906, 1872.8220819022197, 1916.7987361327052, 8620.956895215522, 9276.489793061395, 9279.814403711931, 9281.071892810724, 9284.0, 8283.875741863862, 8558.101399353072, 8944.59749311281, 9001.099890010995, 9023.278137748981, 2386.9283921482383, 2444.7799698027175, 2447.229122826313, 2448.5959816630234, 2451.2156110044793, 2413.3242692046256, 2460.6186041163646, 2464.827462077655, 2481.491294284667, 2481.627755836621, 2165.4153378587853, 2231.5313784105274, 2231.9107235710553, 2232.4530490029965, 2388.6775285336516, 2307.711536057993, 2373.0982226753895, 2380.73811880693, 2383.2016274548046, 2384.058296972686, 2422.9757702422926, 2426.757324267559, 2431.720352159489, 2450.816188785848, 2453.7178224504232, 9061.59222834974, 9188.494632795193, 9214.907850921472, 9218.124278193627, 9223.07769223079, 5580.525655319303, 5740.5981581289325, 5745.052066409037, 5754.597178197528, 5756.913646295309, 3376.628570857228, 3467.6358982306747, 3523.365794157051, 3526.929461410783, 3539.681428671406, 1191.7139886427283, 1229.3484453239798, 1231.0889941443268, 1246.2211118051218, 1254.9686257843537, 12368.13423060386, 12688.74622507554, 12688.80966785499, 12720.809187862189, 12785.0, 4832.782524786393, 4891.657583969088, 4927.285543596158, 4958.578520825698, 5031.320771695848, 11151.944240278908, 11153.10775137987, 11153.163512736577, 11158.1631377647, 11165.441727913572, 5600.775968961196, 5611.410801865745, 5615.157726341082, 5622.465865742748, 5622.887542249132, 5078.263651770472, 5114.156164232934, 5133.974330128325, 5193.246979187997, 5206.973965130226, 4320.805563749639, 4335.523092459827, 4356.08522210333, 4412.713173643703, 4432.891777055736, 1369.2263870912902, 1415.978760318596, 1419.1130543410368, 1428.550006747871, 1436.9353379097965, 1033.4006276359694, 1036.2694300518117, 1044.535181844072, 1045.0698877998586, 1048.8479170520231, 6326.304106548276, 6332.936670633235, 6347.873042539123, 6468.126802881645, 6517.902231466532, 483.47059969333526, 486.4648886225195, 489.26121556450016, 490.2401278019056, 504.14798989707276, 4283.464566929134, 4318.373835793793, 4444.266695995189, 4461.375407442961, 4550.817967281272, 11712.0, 12046.277223366626, 12074.456649450796, 12104.031677465782, 12110.39448027595, 10939.45302734876, 11620.76758464826, 11654.825177622342, 11806.232594881478, 12015.15893887437, 8519.701810436605, 8728.214460698568, 8735.825283494294, 8742.038375778659, 8745.431546949556, 6534.542582019213, 6545.181852268466, 6562.606243625398, 6625.238097618835, 6807.910734282491, 10569.207309451667, 10776.622818201326, 10807.081398080936, 10832.945835270773, 10952.726181845517, 11205.551777929119, 11220.607278745205, 11252.493637786156, 11259.662210133709, 11310.773784524099, 8029.237222463857, 8162.020557532896, 8167.224113709426, 8209.917900821154, 8433.789155271168, 8871.423357481743, 9035.051319611346, 9036.141566551167, 9039.276857851224, 9045.050269721585, 7935.2064793520185, 8063.25849211271, 8175.0, 8227.177282271725, 8377.874331884905, 7571.318581327487, 7648.579328137006, 7665.271799179069, 7695.461317707706, 7770.650320735694, 7500.662470188964, 7575.318221360104, 7607.923920760722, 7609.619519024137, 7610.771676849703, 5611.074172761449, 5637.126245431965, 5653.0, 5673.971630141823, 5816.3892791258, 5449.100898351728, 5492.972535137458, 5564.638298510584, 5609.4390560943575, 5691.032524470767], "value": 4847.088616870043, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.64089526444866, 345.8599267296744, 351.3166890398175, 358.8510768031267, 359.9586047604525, 5590.972045139773, 5621.831345059644, 5669.971650141749, 5674.943250567493, 5679.119736440848, 5474.671519708811, 5502.944970550293, 5587.245721827552, 5588.608797384175, 5594.049011668016, 3845.5577608574954, 4109.650679692222, 4242.53332133465, 4281.657467402601, 4288.463942007249, 4295.935560966573, 4301.268784306675, 4303.462067241595, 4304.956950430487, 4323.82704691812, 4293.978530107361, 4300.193918554199, 4304.354346847982, 4321.459817522809, 4331.501877284106, 4133.958660413387, 4230.598093181143, 4321.762303073329, 4330.155619654169, 4336.414584031147, 3805.7906815125157, 3813.828377723009, 3818.6563209311294, 3829.349010668193, 3862.246861861938, 5375.172441369914, 5400.162974738922, 5423.2407462954825, 5427.62006659534, 5444.183372494118, 4766.666333356667, 4915.754212289371, 4948.7278199699, 4952.727599981999, 4988.725620090893, 5692.231548740909, 5710.65736055838, 5724.084146536534, 5737.512311453532, 5756.481916627524, 4214.85248016321, 4217.662586993033, 4220.2192594369835, 4222.493300803923, 4253.383259427396, 5691.715414229272, 5853.414658534155, 5858.83223207416, 6041.395860413967, 6092.939070609325, 4290.206311832321, 4331.545187755303, 4341.175176716477, 4351.586599273064, 4361.018770776614, 4392.143532011291, 4401.317795741666, 4405.581469760367, 4423.800928958204, 4425.424694789656, 4901.926471102936, 4989.201727723546, 5007.246014691905, 5008.9982003599425, 5027.547520723152, 5749.396313387034, 5794.333651630032, 5795.739191736382, 5833.7374818133285, 5846.210761547221, 1508.374024779704, 1531.946381876629, 1532.762421824619, 1532.9003614765004, 1536.9462068827538, 943.7923656795494, 979.4025644356936, 986.063239922077, 998.9950050249847, 1016.6390931219458, 4876.561109500162, 4958.7024778513405, 4973.35346404965, 4989.977054703777, 4998.2002879539095, 5218.2955301034635, 5237.6333656643665, 5265.552428043659, 5302.912902854905, 5317.7872885084935, 4993.201087825931, 5016.36640763602, 5076.492350764894, 5245.632805703641, 5646.802361917313, 3141.9057428277188, 3176.0154352150907, 3187.824669643192, 3189.2983543620367, 3189.9521507176946, 6066.666333351708, 6196.566240363131, 6198.783042593487, 6230.784996925556, 6248.625082495065, 6016.127661489059, 6046.153538504613, 6070.423309785563, 6072.99795533732, 6090.451859332593, 6185.907211391833, 6188.597741146901, 6195.380461953769, 6197.721102550396, 6226.221722284715, 5842.342096515716, 5865.501432378125, 5906.291245050621, 5940.79207227745, 6011.609245399035, 5857.853553661193, 5881.2942446906645, 5911.7635294587735, 5930.547015981112, 5932.406759324033, 5832.617082092461, 5880.647161170344, 6018.458338749533, 6035.336113027652, 6161.229846269216, 4144.91710165801, 4281.0, 4296.699231053796, 4300.956990430056, 4318.711083125948, 5985.832762611249, 5998.790042348497, 6008.909866351921, 6035.668038257762, 6048.667323297261, 5602.971985140048, 5788.21859049023, 5792.536597072309, 5855.238818953509, 5888.882222355443, 5795.536357091339, 5833.504152146946, 5858.9865941829985, 5861.736221869941, 5867.559933004956, 5808.419158084158, 5830.679312637845, 5833.912491312549, 5869.8239052828485, 5872.853178670567, 5737.053386191232, 5843.29880414353, 5862.94137058624, 5868.178455016387, 5901.822945311647, 5728.427157284238, 5744.281964754406, 5747.672192761938, 5748.310202775694, 5761.769529218785], "value": 4409.206902903914, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.5059640110536, 857.2713193785281, 868.7393781865443, 894.5080205886765, 935.8081593273371, 16873.746893796586, 18253.904765714065, 18458.907705461454, 18486.537836554075, 18510.0, 15421.15183664898, 15520.767188492182, 16060.598485037879, 16583.253753581088, 16804.74792878108, 889.8309321228978, 909.0999910088997, 910.4901255297021, 911.3347256502724, 911.5852287209337, 738.8152961759561, 879.2394578689419, 895.2882458445551, 896.8789213456165, 904.7738065483629, 956.7703751099721, 959.4483172175992, 960.1838437328284, 960.5581432541005, 961.5048250151178, 952.5570609666538, 954.7660823098386, 955.0592666223764, 955.0926619711267, 955.2835373469898, 574.029889486766, 585.7247093865918, 672.9899051514232, 706.6643344411415, 707.119636053112, 15804.762928556082, 16940.407085752064, 16986.15069246545, 17013.319467221296, 17016.914915425343, 15805.446809361618, 16127.83872161275, 16159.515214543464, 16241.106739129344, 16361.727654469154, 13148.934255328757, 13165.934170329183, 13291.667708307274, 13480.0, 13647.38586763598, 4686.929696054562, 4692.296155576691, 4745.311929770164, 4772.188727916263, 4804.807807687724, 87111.0, 87802.0, 89013.55493222557, 90254.5487272566, 90578.54710726404, 11609.30344179352, 11652.592159274385, 11757.294562326288, 11830.704232394259, 11917.046636269077, 24689.135880244463, 24692.0, 24695.0, 24703.0, 24703.3824154394, 9461.716148515556, 9640.084192001748, 9677.612895484242, 9727.65953191649, 9832.0, 5142.357205349332, 5165.612579056586, 5194.831023801328, 5305.522502974751, 5412.134058550691, 3830.751001184914, 3990.640842324147, 3992.6007399260425, 4000.9597504649, 4006.1787333596544, 645.6771614192903, 652.5823472977298, 653.7515744017243, 657.0964923230558, 694.350782018814, 620.9161763162006, 636.9999101410833, 741.9183889772121, 747.5813544415145, 747.9289467500579, 10093.545790439448, 10433.478326083665, 10567.89432105669, 10788.838167427495, 10817.37561406032, 614.7756069034859, 665.7137430904758, 712.262807993724, 769.9345555627834, 792.5720111140035, 4831.855044348676, 4850.53919877611, 4894.461609222983, 4909.705417674951, 4939.75301234944, 122045.38977305057, 124287.0, 127157.36421317834, 129832.35083824709, 134124.32937835442, 65001.34998650048, 65540.67229663821, 100585.0, 103380.0, 108951.45524272328, 57228.42771572231, 57883.0, 68614.65692671505, 103588.0, 106743.46628266809, 9336.579853906725, 9651.517424128906, 9657.855132173163, 9663.0, 9687.564059617192, 44827.327590086825, 51997.0, 52761.7361913188, 53265.73367133294, 59586.10620840778, 49490.010199796525, 51111.48888511216, 66624.6668766653, 69073.0, 76651.0, 14176.6359091698, 14460.2046887418, 14544.781828272373, 14601.612846779539, 14793.92603036978, 14577.344019518934, 15047.623809404853, 15626.062096403293, 16125.516234512983, 17238.310467581658, 15249.3900243989, 15530.84469155294, 16453.753193701865, 17083.231254593822, 17582.472525824247, 15232.116469806266, 15318.540443786704, 15599.610009749847, 15951.282192301142, 16149.19254037317, 15170.772438413213, 15263.819095477511, 15336.693266134838, 15533.611659708598, 15640.765388519409, 10867.574454530824, 11517.308961462339, 11773.0, 12301.876981230072, 12317.260964342167, 11604.6518604442, 11616.0, 11649.534018639159, 11670.533178672758, 11764.647060588197], "value": 8147.628348734811, "unit": "score"}], "name": "dromaeo_css", "value": 7756.319894630564, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 112.0, 111.0, 110.0, 112.0, 111.0, 109.0, 112.0, 110.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 120.0, 173.0, 117.0, 118.0, 141.0, 116.0, 162.0, 118.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 161.0, 159.0, 162.0, 163.0, 161.0, 164.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 112.0, 75.0, 76.0, 77.0, 76.0, 100.0, 75.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 128.0, 83.0, 83.0, 83.0, 120.0, 84.0, 83.0, 130.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 118.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 147.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 51.0, 50.0, 52.0, 53.0, 52.0, 51.0, 49.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 59.0, 58.0, 59.0, 60.0, 60.0, 61.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 82.0, 72.0, 73.0, 72.0, 72.0, 82.0, 78.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 117.0, 117.0, 117.0, 115.0, 116.0, 117.0, 124.0, 148.0, 120.0], "value": 121.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 157.0, 150.0, 159.0, 155.0, 151.0, 154.0, 150.0, 151.0, 147.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 63.0, 61.0, 61.0, 61.0, 65.0, 63.0, 60.0, 60.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1460.3, "alertThreshold": 2.0}]} 04:33:27 INFO - Return code: 0 04:33:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:33:27 INFO - # TBPL SUCCESS # 04:33:27 INFO - Running post-action listener: _resource_record_post_action 04:33:27 INFO - [mozharness: 2016-08-18 11:33:27.325995Z] Finished run-tests step (success) 04:33:27 INFO - Running post-run listener: _resource_record_post_run 04:33:27 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 324509696; Read time: 8; Write time: 721292 04:33:27 INFO - TinderboxPrint: CPU usage
13.0% 04:33:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 04:33:27 INFO - TinderboxPrint: I/O write bytes / time
324,509,696 / 721,292 04:33:27 INFO - TinderboxPrint: CPU idle
8,278.7 (87.0%) 04:33:27 INFO - TinderboxPrint: CPU user
1,183.8 (12.4%) 04:33:27 INFO - TinderboxPrint: Swap in / out
0 / 0 04:33:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79056896; Read time: 0; Write time: 181388 04:33:27 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 244895744; Read time: 0; Write time: 539756 04:33:27 INFO - Running post-run listener: _upload_blobber_files 04:33:27 INFO - Blob upload gear active. 04:33:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:33:27 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:33:27 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:33:27 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:33:28 INFO - (blobuploader) - INFO - Open directory for files ... 04:33:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:33:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:33:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:40 INFO - (blobuploader) - INFO - Done attempting. 04:33:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:33:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:33:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:40 INFO - (blobuploader) - INFO - Done attempting. 04:33:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:33:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:33:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:41 INFO - (blobuploader) - INFO - Done attempting. 04:33:41 INFO - (blobuploader) - INFO - Iteration through files over. 04:33:41 INFO - Return code: 0 04:33:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:33:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:33:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9529f338ddc18c421d92b4c8e2e782e2bd0dcc1be87592f723fa85bb3218397ea56ced77d1970c16ab769145fc004de46eb0e58fa0a339aa8860fe958c105451", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc11552e39134ba40957e05e21e3def57f1d023216cb5861f70c1f284047b6bc21f7a5e68397d2a79dd12d85474b89103c0bf557ff8a2b36a6061b04a0371dff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/488470bb5e86de120da6fdf8f6eb5b1f73d316fb0555847e606f23c0b70ae0613b064d40bc186f628ddc4f89880c887e7767584090034f24f4e7968f0ccf1fd2"} 04:33:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:33:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:33:41 INFO - Contents: 04:33:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9529f338ddc18c421d92b4c8e2e782e2bd0dcc1be87592f723fa85bb3218397ea56ced77d1970c16ab769145fc004de46eb0e58fa0a339aa8860fe958c105451", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc11552e39134ba40957e05e21e3def57f1d023216cb5861f70c1f284047b6bc21f7a5e68397d2a79dd12d85474b89103c0bf557ff8a2b36a6061b04a0371dff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/488470bb5e86de120da6fdf8f6eb5b1f73d316fb0555847e606f23c0b70ae0613b064d40bc186f628ddc4f89880c887e7767584090034f24f4e7968f0ccf1fd2"} 04:33:41 INFO - Running post-run listener: copy_logs_to_upload_dir 04:33:41 INFO - Copying logs to upload dir... 04:33:41 INFO - mkdir: /builds/slave/test/build/upload/logs 04:33:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1691.674799 ========= master_lag: 1.64 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 13 secs) (at 2016-08-18 04:33:43.144374) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-18 04:33:43.149067) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598 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/9529f338ddc18c421d92b4c8e2e782e2bd0dcc1be87592f723fa85bb3218397ea56ced77d1970c16ab769145fc004de46eb0e58fa0a339aa8860fe958c105451", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc11552e39134ba40957e05e21e3def57f1d023216cb5861f70c1f284047b6bc21f7a5e68397d2a79dd12d85474b89103c0bf557ff8a2b36a6061b04a0371dff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/488470bb5e86de120da6fdf8f6eb5b1f73d316fb0555847e606f23c0b70ae0613b064d40bc186f628ddc4f89880c887e7767584090034f24f4e7968f0ccf1fd2"} build_url:https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/RZ-8QT-hSAq_3rbubKvF2A/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/9529f338ddc18c421d92b4c8e2e782e2bd0dcc1be87592f723fa85bb3218397ea56ced77d1970c16ab769145fc004de46eb0e58fa0a339aa8860fe958c105451", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc11552e39134ba40957e05e21e3def57f1d023216cb5861f70c1f284047b6bc21f7a5e68397d2a79dd12d85474b89103c0bf557ff8a2b36a6061b04a0371dff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/488470bb5e86de120da6fdf8f6eb5b1f73d316fb0555847e606f23c0b70ae0613b064d40bc186f628ddc4f89880c887e7767584090034f24f4e7968f0ccf1fd2"}' ========= master_lag: 1.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-18 04:33:44.258676) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-08-18 04:33:44.259079) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471517958.761067-439658598 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 2.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-08-18 04:33:46.315733) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:33:46.316033) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:33:46.471011) ========= ========= Total master_lag: 4.90 =========