builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1471518328.68 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.678113) ========= 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.678616) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.678894) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.831027) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:28.831281) ========= 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=fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372 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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|: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.9M=0s 2016-08-18 04:05:29 (37.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133076 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.000189) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.000454) ========= 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=fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020783 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.064349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.064655) ========= 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=fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 04:05:29,975 truncating revision to first 12 chars 2016-08-18 04:05:29,975 Setting DEBUG logging. 2016-08-18 04:05:29,975 attempt 1/10 2016-08-18 04:05:29,975 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:30,312 unpacking tar archive at: mozilla-aurora-8ececbd9880d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.700882 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.788592) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.788867) ========= script_repo_revision: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.789224) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.789483) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 04:05:29.802877) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 12 secs) (at 2016-08-18 04:05:29.803133) ========= /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=fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372 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-048', 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.805090Z] 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:31 INFO - Running post-action listener: _resource_record_post_action 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.257579Z] Finished clobber step (success) 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.257679Z] Running read-buildbot-config step. 04:05:31 INFO - Running pre-action listener: _resource_record_pre_action 04:05:31 INFO - Running main action method: read_buildbot_config 04:05:31 INFO - Using buildbot properties: 04:05:31 INFO - { 04:05:31 INFO - "project": "", 04:05:31 INFO - "product": "firefox", 04:05:31 INFO - "script_repo_revision": "production", 04:05:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:05:31 INFO - "repository": "", 04:05:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:05:31 INFO - "buildid": "20160817151514", 04:05:31 INFO - "pgo_build": "True", 04:05:31 INFO - "basedir": "/builds/slave/test", 04:05:31 INFO - "buildnumber": 688, 04:05:31 INFO - "slavename": "talos-linux64-ix-048", 04:05:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:05:31 INFO - "platform": "ubuntu64_hw", 04:05:31 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:05:31 INFO - "branch": "mozilla-aurora", 04:05:31 INFO - "repo_path": "releases/mozilla-aurora", 04:05:31 INFO - "revision": "8ececbd9880dc48a879d7a0d44dbf5e15248b09c", 04:05:31 INFO - "stage_platform": "linux64-pgo", 04:05:31 INFO - "builduid": "5ca560887ea949039c2417d51dd6fc7c", 04:05:31 INFO - "slavebuilddir": "test" 04:05:31 INFO - } 04:05:31 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:31 INFO - Running post-action listener: _resource_record_post_action 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.260108Z] Finished read-buildbot-config step (success) 04:05:31 INFO - [mozharness: 2016-08-18 11:05:31.260197Z] Running download-and-extract step. 04:05:31 INFO - Running pre-action listener: _resource_record_pre_action 04:05:31 INFO - Running main action method: download_and_extract 04:05:31 INFO - mkdir: /builds/slave/test/build/tests 04:05:31 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:31 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:31 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:31 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:31 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:31 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: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.test_packages.json 04:05:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:05:31 INFO - retry: Failed, sleeping 30 seconds before retrying 04:06:01 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: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.test_packages.json 04:06:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:06:01 INFO - retry: Failed, sleeping 60 seconds before retrying 04:07:01 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: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.test_packages.json 04:07:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:07:01 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:01 INFO - Caught exception: HTTP Error 404: Not Found 04:07:01 INFO - Caught exception: HTTP Error 404: Not Found 04:07:01 INFO - Caught exception: HTTP Error 404: Not Found 04:07:01 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:01 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:01 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:32 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:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:07:32 INFO - retry: Failed, sleeping 60 seconds before retrying 04:08:32 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:32 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:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:08:32 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:32 INFO - Caught exception: HTTP Error 404: Not Found 04:08:32 INFO - Caught exception: HTTP Error 404: Not Found 04:08:32 INFO - Caught exception: HTTP Error 404: Not Found 04:08:32 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:32 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:32 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:33 INFO - Downloaded 21766774 bytes. 04:08:33 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:33 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:34 INFO - Return code: 0 04:08:34 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: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:08:34 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:34 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:34 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:34 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:34 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:34 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:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:08:34 INFO - retry: Failed, sleeping 30 seconds before retrying 04:09:04 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:04 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:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:09:04 INFO - retry: Failed, sleeping 60 seconds before retrying 04:10:04 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:04 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:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:10:04 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:04 INFO - Caught exception: HTTP Error 404: Not Found 04:10:04 INFO - Caught exception: HTTP Error 404: Not Found 04:10:04 INFO - Caught exception: HTTP Error 404: Not Found 04:10:04 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:04 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:04 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:05 INFO - Downloaded 21766774 bytes. 04:10:05 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:05 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:07 INFO - Return code: 0 04:10:07 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:07 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:07 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:07 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:07 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:07 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: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.talos.tests.zip 04:10:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:10:07 INFO - retry: Failed, sleeping 30 seconds before retrying 04:10:37 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: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.talos.tests.zip 04:10:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:10:37 INFO - retry: Failed, sleeping 60 seconds before retrying 04:11:37 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: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.talos.tests.zip 04:11:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:11:37 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:37 INFO - Caught exception: HTTP Error 404: Not Found 04:11:37 INFO - Caught exception: HTTP Error 404: Not Found 04:11:37 INFO - Caught exception: HTTP Error 404: Not Found 04:11:37 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:37 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:37 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:37 INFO - Downloaded 14413723 bytes. 04:11:37 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:37 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:38 INFO - Return code: 0 04:11:38 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:38 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:38 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:38 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:38 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:38 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:38 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:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:11:38 INFO - retry: Failed, sleeping 30 seconds before retrying 04:12:08 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:08 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:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:12:08 INFO - retry: Failed, sleeping 60 seconds before retrying 04:13:08 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:08 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:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:13:08 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:08 INFO - Caught exception: HTTP Error 404: Not Found 04:13:08 INFO - Caught exception: HTTP Error 404: Not Found 04:13:08 INFO - Caught exception: HTTP Error 404: Not Found 04:13:08 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:08 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:08 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:14 INFO - Downloaded 63785079 bytes. 04:13:14 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:14 INFO - mkdir: /builds/slave/test/properties 04:13:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:13:14 INFO - Writing to file /builds/slave/test/properties/build_url 04:13:14 INFO - Contents: 04:13:14 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:15 INFO - Running post-action listener: _resource_record_post_action 04:13:15 INFO - Running post-action listener: set_extra_try_arguments 04:13:15 INFO - [mozharness: 2016-08-18 11:13:15.437828Z] Finished download-and-extract step (success) 04:13:15 INFO - [mozharness: 2016-08-18 11:13:15.438061Z] Running populate-webroot step. 04:13:15 INFO - Running pre-action listener: _resource_record_pre_action 04:13:15 INFO - Running main action method: populate_webroot 04:13:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:13:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:13:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:13:15 INFO - '--tppagecycles', 04:13:15 INFO - '7'], 04:13:15 INFO - 'tests': ['tsvgm']}}, 04:13:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:13:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:13:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:13:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:13:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:15 INFO - 'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:13:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:15 INFO - 'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['damp', 'tps']}, 04:13:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:15 INFO - 'tests': ['damp', 'tps']}, 04:13:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['dromaeo_dom']}, 04:13:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:13:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['basic_compositor_video']}, 04:13:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:13:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['a11yr', 04:13:15 INFO - 'ts_paint', 04:13:15 INFO - 'tpaint', 04:13:15 INFO - 'sessionrestore', 04:13:15 INFO - 'sessionrestore_no_auto_restore', 04:13:15 INFO - 'tabpaint']}, 04:13:15 INFO - 'other-e10s': {'tests': ['a11yr', 04:13:15 INFO - 'ts_paint', 04:13:15 INFO - 'tpaint', 04:13:15 INFO - 'sessionrestore', 04:13:15 INFO - 'sessionrestore_no_auto_restore', 04:13:15 INFO - 'tabpaint']}, 04:13:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:13:15 INFO - 'ts_paint', 04:13:15 INFO - 'tpaint', 04:13:15 INFO - 'sessionrestore', 04:13:15 INFO - 'sessionrestore_no_auto_restore', 04:13:15 INFO - 'tabpaint']}, 04:13:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:13:15 INFO - 'ts_paint', 04:13:15 INFO - 'tpaint', 04:13:15 INFO - 'sessionrestore', 04:13:15 INFO - 'sessionrestore_no_auto_restore', 04:13:15 INFO - 'tabpaint']}, 04:13:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['a11yr', 04:13:15 INFO - 'ts_paint', 04:13:15 INFO - 'tpaint', 04:13:15 INFO - 'sessionrestore', 04:13:15 INFO - 'sessionrestore_no_auto_restore', 04:13:15 INFO - 'tabpaint']}, 04:13:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['a11yr', 04:13:15 INFO - 'ts_paint', 04:13:15 INFO - 'tpaint', 04:13:15 INFO - 'sessionrestore', 04:13:15 INFO - 'sessionrestore_no_auto_restore', 04:13:15 INFO - 'tabpaint']}, 04:13:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['tsvgx', 04:13:15 INFO - 'tsvgr_opacity', 04:13:15 INFO - 'tart', 04:13:15 INFO - 'tscrollx', 04:13:15 INFO - 'cart']}, 04:13:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:13:15 INFO - 'tsvgr_opacity', 04:13:15 INFO - 'tart', 04:13:15 INFO - 'tscrollx', 04:13:15 INFO - 'cart']}, 04:13:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:15 INFO - 'talos_options': ['--disable-e10s'], 04:13:15 INFO - 'tests': ['tp5o']}, 04:13:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:15 INFO - 'tests': ['tp5o']}, 04:13:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:15 INFO - 'talos_options': ['--disable-e10s', 04:13:15 INFO - '--xperf_path', 04:13:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:15 INFO - 'tests': ['tp5n']}, 04:13:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:13:15 INFO - 'talos_options': ['--xperf_path', 04:13:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:13:15 INFO - 'tests': ['tp5n']}}, 04:13:15 INFO - 'talos.zip': {'path': '', 04:13:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:13:15 INFO - Running post-action listener: _resource_record_post_action 04:13:15 INFO - [mozharness: 2016-08-18 11:13:15.455462Z] Finished populate-webroot step (success) 04:13:15 INFO - [mozharness: 2016-08-18 11:13:15.455551Z] Running create-virtualenv step. 04:13:15 INFO - Running pre-action listener: _resource_record_pre_action 04:13:15 INFO - Running main action method: create_virtualenv 04:13:15 INFO - Creating virtualenv /builds/slave/test/build/venv 04:13:15 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:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:13:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:13:15 INFO - Using real prefix '/usr' 04:13:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:13:16 INFO - Installing distribute.............................................................................................................................................................................................done. 04:13:18 INFO - Installing pip.................done. 04:13:18 INFO - Return code: 0 04:13:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:13:18 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:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:18 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:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:18 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 0x17be030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, '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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', '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:18 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:18 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:18 INFO - Using env: {'DISPLAY': ':0', 04:13:18 INFO - 'HOME': '/home/cltbld', 04:13:18 INFO - 'LANG': 'en_US.UTF-8', 04:13:18 INFO - 'LANGUAGE': 'en_US:en', 04:13:18 INFO - 'LOGNAME': 'cltbld', 04:13:18 INFO - 'MAIL': '/var/mail/cltbld', 04:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:18 INFO - 'MOZ_NO_REMOTE': '1', 04:13:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:18 INFO - 'NO_EM_RESTART': '1', 04:13:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:18 INFO - 'PWD': '/builds/slave/test', 04:13:18 INFO - 'SHELL': '/bin/bash', 04:13:18 INFO - 'SHLVL': '1', 04:13:18 INFO - 'TERM': 'linux', 04:13:18 INFO - 'TMOUT': '86400', 04:13:18 INFO - 'USER': 'cltbld', 04:13:18 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 04:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:18 INFO - '_': '/tools/buildbot/bin/python'} 04:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:18 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:18 INFO - Cleaning up... 04:13:18 INFO - Return code: 0 04:13:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:13:18 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:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:18 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:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:18 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 0x17be030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, '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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', '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:18 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:18 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:18 INFO - Using env: {'DISPLAY': ':0', 04:13:18 INFO - 'HOME': '/home/cltbld', 04:13:18 INFO - 'LANG': 'en_US.UTF-8', 04:13:18 INFO - 'LANGUAGE': 'en_US:en', 04:13:18 INFO - 'LOGNAME': 'cltbld', 04:13:18 INFO - 'MAIL': '/var/mail/cltbld', 04:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:18 INFO - 'MOZ_NO_REMOTE': '1', 04:13:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:18 INFO - 'NO_EM_RESTART': '1', 04:13:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:18 INFO - 'PWD': '/builds/slave/test', 04:13:18 INFO - 'SHELL': '/bin/bash', 04:13:18 INFO - 'SHLVL': '1', 04:13:18 INFO - 'TERM': 'linux', 04:13:18 INFO - 'TMOUT': '86400', 04:13:18 INFO - 'USER': 'cltbld', 04:13:18 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 04:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:18 INFO - '_': '/tools/buildbot/bin/python'} 04:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:18 INFO - Downloading/unpacking psutil>=3.1.1 04:13:18 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:18 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:18 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:18 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:18 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:18 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 - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:13:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:20 INFO - Installing collected packages: psutil 04:13:20 INFO - Running setup.py install for psutil 04:13:20 INFO - building 'psutil._psutil_linux' extension 04:13:20 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:20 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:21 INFO - building 'psutil._psutil_posix' extension 04:13:21 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:21 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:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:13:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:13:21 INFO - Successfully installed psutil 04:13:21 INFO - Cleaning up... 04:13:21 INFO - Return code: 0 04:13:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:13:21 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:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:21 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:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:21 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 0x17be030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, '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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', '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:21 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:21 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:21 INFO - Using env: {'DISPLAY': ':0', 04:13:21 INFO - 'HOME': '/home/cltbld', 04:13:21 INFO - 'LANG': 'en_US.UTF-8', 04:13:21 INFO - 'LANGUAGE': 'en_US:en', 04:13:21 INFO - 'LOGNAME': 'cltbld', 04:13:21 INFO - 'MAIL': '/var/mail/cltbld', 04:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:21 INFO - 'MOZ_NO_REMOTE': '1', 04:13:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:21 INFO - 'NO_EM_RESTART': '1', 04:13:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:21 INFO - 'PWD': '/builds/slave/test', 04:13:21 INFO - 'SHELL': '/bin/bash', 04:13:21 INFO - 'SHLVL': '1', 04:13:21 INFO - 'TERM': 'linux', 04:13:21 INFO - 'TMOUT': '86400', 04:13:21 INFO - 'USER': 'cltbld', 04:13:21 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 04:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:21 INFO - '_': '/tools/buildbot/bin/python'} 04:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:13:21 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:21 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:21 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:21 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:21 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:21 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 - Downloading mozsystemmonitor-0.3.tar.gz 04:13:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:13:23 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:23 INFO - Installing collected packages: mozsystemmonitor 04:13:23 INFO - Running setup.py install for mozsystemmonitor 04:13:23 INFO - Successfully installed mozsystemmonitor 04:13:23 INFO - Cleaning up... 04:13:23 INFO - Return code: 0 04:13:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:13:24 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:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:24 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:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:24 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 0x17be030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, '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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', '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:24 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:24 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:24 INFO - Using env: {'DISPLAY': ':0', 04:13:24 INFO - 'HOME': '/home/cltbld', 04:13:24 INFO - 'LANG': 'en_US.UTF-8', 04:13:24 INFO - 'LANGUAGE': 'en_US:en', 04:13:24 INFO - 'LOGNAME': 'cltbld', 04:13:24 INFO - 'MAIL': '/var/mail/cltbld', 04:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:24 INFO - 'MOZ_NO_REMOTE': '1', 04:13:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:24 INFO - 'NO_EM_RESTART': '1', 04:13:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:24 INFO - 'PWD': '/builds/slave/test', 04:13:24 INFO - 'SHELL': '/bin/bash', 04:13:24 INFO - 'SHLVL': '1', 04:13:24 INFO - 'TERM': 'linux', 04:13:24 INFO - 'TMOUT': '86400', 04:13:24 INFO - 'USER': 'cltbld', 04:13:24 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 04:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:24 INFO - '_': '/tools/buildbot/bin/python'} 04:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:24 INFO - Downloading/unpacking jsonschema==2.5.1 04:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:13:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:13:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:13:26 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:26 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 - 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:26 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:26 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:26 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 functools32-3.2.3-2.tar.gz 04:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:13:26 INFO - warning: no files found matching '*.txt' 04:13:26 INFO - no previously-included directories found matching 'build' 04:13:26 INFO - no previously-included directories found matching 'dist' 04:13:26 INFO - no previously-included directories found matching '.git*' 04:13:26 INFO - Installing collected packages: jsonschema, functools32 04:13:26 INFO - Running setup.py install for functools32 04:13:26 INFO - warning: no files found matching '*.txt' 04:13:26 INFO - no previously-included directories found matching 'build' 04:13:26 INFO - no previously-included directories found matching 'dist' 04:13:26 INFO - no previously-included directories found matching '.git*' 04:13:26 INFO - Successfully installed jsonschema functools32 04:13:26 INFO - Cleaning up... 04:13:26 INFO - Return code: 0 04:13:26 INFO - Installing functools32==3.2.3-2 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', '--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 0x17be030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, '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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', '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', '--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:26 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: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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 04:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:26 INFO - '_': '/tools/buildbot/bin/python'} 04:13:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:13:26 INFO - Cleaning up... 04:13:26 INFO - Return code: 0 04:13:26 INFO - Installing blobuploader==1.2.4 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', '--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 0x17be030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, '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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', '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', '--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:26 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: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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 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 - Downloading/unpacking blobuploader==1.2.4 04:13:27 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:27 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:27 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:27 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:27 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:27 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:29 INFO - Downloading blobuploader-1.2.4.tar.gz 04:13:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:13:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:13:29 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:29 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:29 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:29 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:29 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:29 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:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:13:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:13:29 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:29 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:29 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:29 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:29 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:29 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:29 INFO - Downloading docopt-0.6.1.tar.gz 04:13:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:13:29 INFO - Installing collected packages: blobuploader, requests, docopt 04:13:29 INFO - Running setup.py install for blobuploader 04:13:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:13:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:13:29 INFO - Running setup.py install for requests 04:13:30 INFO - Running setup.py install for docopt 04:13:30 INFO - Successfully installed blobuploader requests docopt 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', '--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 0x17be030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, '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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', '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', '--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:30 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: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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 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-7k72nQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:30 INFO - Running setup.py (path:/tmp/pip-laZWLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:30 INFO - Running setup.py (path:/tmp/pip-VNjifz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:30 INFO - Running setup.py (path:/tmp/pip-VpmfcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:30 INFO - Running setup.py (path:/tmp/pip-rj02YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:31 INFO - Running setup.py (path:/tmp/pip-6TUr8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:31 INFO - Running setup.py (path:/tmp/pip-Q8NSk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:31 INFO - Running setup.py (path:/tmp/pip-dFKFCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:31 INFO - Running setup.py (path:/tmp/pip-yBm4pC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:31 INFO - Running setup.py (path:/tmp/pip-R_dZoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:31 INFO - Running setup.py (path:/tmp/pip-k1WZB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:31 INFO - Running setup.py (path:/tmp/pip-gWbcPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:31 INFO - Running setup.py (path:/tmp/pip-Cpb7W4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:31 INFO - Running setup.py (path:/tmp/pip-Trv1qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:31 INFO - Running setup.py (path:/tmp/pip-uPqnml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:31 INFO - Running setup.py (path:/tmp/pip-CG9Y1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:31 INFO - Running setup.py (path:/tmp/pip-Z0YvfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:13:31 INFO - Running setup.py install for manifestparser 04:13:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:13:31 INFO - Running setup.py install for mozcrash 04:13:32 INFO - Running setup.py install for mozdebug 04:13:32 INFO - Running setup.py install for mozdevice 04:13:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:13:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:13:32 INFO - Running setup.py install for mozfile 04:13:32 INFO - Running setup.py install for mozhttpd 04:13:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:13:32 INFO - Running setup.py install for mozinfo 04:13:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:13:32 INFO - Running setup.py install for mozInstall 04:13:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:13:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:13:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:13:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:13:32 INFO - Running setup.py install for mozleak 04:13:32 INFO - Running setup.py install for mozlog 04:13:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:13:32 INFO - Running setup.py install for moznetwork 04:13:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Running setup.py install for mozprocess 04:13:33 INFO - Running setup.py install for mozprofile 04:13:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Running setup.py install for mozrunner 04:13:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Running setup.py install for mozscreenshot 04:13:33 INFO - Running setup.py install for moztest 04:13:33 INFO - Running setup.py install for mozversion 04:13:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:13:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:13:33 INFO - Cleaning up... 04:13:33 INFO - Return code: 0 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/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 0x17be030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, '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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', '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/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:33 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: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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 04:13:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:33 INFO - '_': '/tools/buildbot/bin/python'} 04:13:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:13:33 INFO - Running setup.py (path:/tmp/pip-0d_WvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:13:33 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:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:13:33 INFO - Running setup.py (path:/tmp/pip-aPJtrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:13:34 INFO - Running setup.py (path:/tmp/pip-cxOmCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:13:34 INFO - Running setup.py (path:/tmp/pip-mTPeT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:13:34 INFO - Running setup.py (path:/tmp/pip-76P0GH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:13:34 INFO - Running setup.py (path:/tmp/pip-9LG2cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:13:34 INFO - Running setup.py (path:/tmp/pip-AOnEAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:13:34 INFO - Running setup.py (path:/tmp/pip-3IAbhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:13:34 INFO - Running setup.py (path:/tmp/pip-E21Jk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:13:34 INFO - Running setup.py (path:/tmp/pip-McaVnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:13:34 INFO - Running setup.py (path:/tmp/pip-SekGyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:13:34 INFO - Running setup.py (path:/tmp/pip-75rjHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:13:34 INFO - Running setup.py (path:/tmp/pip-jtc7Dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:13:34 INFO - Running setup.py (path:/tmp/pip-xVcVGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:13:34 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:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:34 INFO - Running setup.py (path:/tmp/pip-Ptgg3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:13:35 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:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:13:35 INFO - Running setup.py (path:/tmp/pip-VQZXdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:13:35 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:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:13:35 INFO - Running setup.py (path:/tmp/pip-jM3MDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:13:35 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:35 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:35 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:35 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:35 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:35 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:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:13:35 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:35 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:35 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:35 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:35 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:35 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:37 INFO - Downloading blessings-1.6.tar.gz 04:13:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:13:37 INFO - Installing collected packages: blessings 04:13:37 INFO - Running setup.py install for blessings 04:13:37 INFO - Successfully installed blessings 04:13:37 INFO - Cleaning up... 04:13:37 INFO - Return code: 0 04:13:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:13:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:37 INFO - Reading from file tmpfile_stdout 04:13:37 INFO - Current package versions: 04:13:37 INFO - argparse == 1.2.1 04:13:37 INFO - blessings == 1.6 04:13:37 INFO - blobuploader == 1.2.4 04:13:37 INFO - docopt == 0.6.1 04:13:37 INFO - functools32 == 3.2.3-2 04:13:37 INFO - jsonschema == 2.5.1 04:13:37 INFO - manifestparser == 1.1 04:13:37 INFO - mozInstall == 1.12 04:13:37 INFO - mozcrash == 0.17 04:13:37 INFO - mozdebug == 0.1 04:13:37 INFO - mozdevice == 0.48 04:13:37 INFO - mozfile == 1.2 04:13:37 INFO - mozhttpd == 0.7 04:13:37 INFO - mozinfo == 0.9 04:13:37 INFO - mozleak == 0.1 04:13:37 INFO - mozlog == 3.3 04:13:37 INFO - moznetwork == 0.27 04:13:37 INFO - mozprocess == 0.23 04:13:37 INFO - mozprofile == 0.28 04:13:37 INFO - mozrunner == 6.12 04:13:37 INFO - mozscreenshot == 0.1 04:13:37 INFO - mozsystemmonitor == 0.3 04:13:37 INFO - moztest == 0.7 04:13:37 INFO - mozversion == 1.4 04:13:37 INFO - psutil == 3.1.1 04:13:37 INFO - requests == 1.2.3 04:13:37 INFO - wsgiref == 0.1.2 04:13:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:13: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:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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:13:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:37 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 0x17be030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, '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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', '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:37 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:37 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:37 INFO - Using env: {'DISPLAY': ':0', 04:13:37 INFO - 'HOME': '/home/cltbld', 04:13:37 INFO - 'LANG': 'en_US.UTF-8', 04:13:37 INFO - 'LANGUAGE': 'en_US:en', 04:13:37 INFO - 'LOGNAME': 'cltbld', 04:13:37 INFO - 'MAIL': '/var/mail/cltbld', 04:13:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:37 INFO - 'MOZ_NO_REMOTE': '1', 04:13:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:37 INFO - 'NO_EM_RESTART': '1', 04:13:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:37 INFO - 'PWD': '/builds/slave/test', 04:13:37 INFO - 'SHELL': '/bin/bash', 04:13:37 INFO - 'SHLVL': '1', 04:13:37 INFO - 'TERM': 'linux', 04:13:37 INFO - 'TMOUT': '86400', 04:13:37 INFO - 'USER': 'cltbld', 04:13:37 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 04:13:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:37 INFO - '_': '/tools/buildbot/bin/python'} 04:13:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:37 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:37 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:37 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:37 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:37 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:37 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:37 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:37 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:37 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:37 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:37 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:37 INFO - Cleaning up... 04:13:37 INFO - Return code: 0 04:13:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:13: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:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:13:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13: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:13:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:13:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:13:37 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 0x17be030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, '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': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', '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:37 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:37 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:37 INFO - Using env: {'DISPLAY': ':0', 04:13:37 INFO - 'HOME': '/home/cltbld', 04:13:37 INFO - 'LANG': 'en_US.UTF-8', 04:13:37 INFO - 'LANGUAGE': 'en_US:en', 04:13:37 INFO - 'LOGNAME': 'cltbld', 04:13:37 INFO - 'MAIL': '/var/mail/cltbld', 04:13:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:37 INFO - 'MOZ_NO_REMOTE': '1', 04:13:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:37 INFO - 'NO_EM_RESTART': '1', 04:13:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:37 INFO - 'PWD': '/builds/slave/test', 04:13:37 INFO - 'SHELL': '/bin/bash', 04:13:37 INFO - 'SHLVL': '1', 04:13:37 INFO - 'TERM': 'linux', 04:13:37 INFO - 'TMOUT': '86400', 04:13:37 INFO - 'USER': 'cltbld', 04:13:37 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 04:13:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:37 INFO - '_': '/tools/buildbot/bin/python'} 04:13:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:13:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:13:37 INFO - Cleaning up... 04:13:37 INFO - Return code: 0 04:13:37 INFO - Running post-action listener: _resource_record_post_action 04:13:37 INFO - Running post-action listener: _start_resource_monitoring 04:13:38 INFO - Starting resource monitoring. 04:13:38 INFO - [mozharness: 2016-08-18 11:13:38.005570Z] Finished create-virtualenv step (success) 04:13:38 INFO - [mozharness: 2016-08-18 11:13:38.006012Z] Running install step. 04:13:38 INFO - Running pre-action listener: _resource_record_pre_action 04:13:38 INFO - Running main action method: install 04:13:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:13:38 INFO - Reading from file tmpfile_stdout 04:13:38 INFO - Detecting whether we're running mozinstall >=1.0... 04:13:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:13:38 INFO - Reading from file tmpfile_stdout 04:13:38 INFO - Output received: 04:13:38 INFO - Usage: mozinstall [options] installer 04:13:38 INFO - Options: 04:13:38 INFO - -h, --help show this help message and exit 04:13:38 INFO - -d DEST, --destination=DEST 04:13:38 INFO - Directory to install application into. [default: 04:13:38 INFO - "/builds/slave/test"] 04:13:38 INFO - --app=APP Application being installed. [default: firefox] 04:13:38 INFO - mkdir: /builds/slave/test/build/application 04:13:38 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:38 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:54 INFO - Reading from file tmpfile_stdout 04:13:54 INFO - Output received: 04:13:54 INFO - /builds/slave/test/build/application/firefox/firefox 04:13:54 INFO - Running post-action listener: _resource_record_post_action 04:13:54 INFO - [mozharness: 2016-08-18 11:13:54.304115Z] Finished install step (success) 04:13:54 INFO - [mozharness: 2016-08-18 11:13:54.304392Z] Running run-tests step. 04:13:54 INFO - Running pre-action listener: _resource_record_pre_action 04:13:54 INFO - Running main action method: run_tests 04:13:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:13:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:13:54 INFO - Python 2.7.3 04:13:54 INFO - Return code: 0 04:13:54 INFO - grabbing minidump binary from tooltool 04:13:54 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:54 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 0x1a7d3b0>, '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 0x1a7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a7e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:13:54 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:54 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:54 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:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:13:54 INFO - Return code: 0 04:13:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:13:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:13:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:13:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:13:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:13:54 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-048', '--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:54 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-048 --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:54 INFO - Using env: {'DISPLAY': ':0', 04:13:54 INFO - 'HOME': '/home/cltbld', 04:13:54 INFO - 'LANG': 'en_US.UTF-8', 04:13:54 INFO - 'LANGUAGE': 'en_US:en', 04:13:54 INFO - 'LOGNAME': 'cltbld', 04:13:54 INFO - 'MAIL': '/var/mail/cltbld', 04:13:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:13:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:13:54 INFO - 'MOZ_NO_REMOTE': '1', 04:13:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:13:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:13:54 INFO - 'NO_EM_RESTART': '1', 04:13:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:13:54 INFO - 'PWD': '/builds/slave/test', 04:13:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:13:54 INFO - 'SHELL': '/bin/bash', 04:13:54 INFO - 'SHLVL': '1', 04:13:54 INFO - 'TERM': 'linux', 04:13:54 INFO - 'TMOUT': '86400', 04:13:54 INFO - 'USER': 'cltbld', 04:13:54 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372', 04:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:13:54 INFO - '_': '/tools/buildbot/bin/python'} 04:13:54 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-048', '--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:54 INFO - mozversion application_buildid: 20160817151514 04:13:54 INFO - mozversion application_changeset: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c 04:13:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:13:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:13:54 INFO - mozversion application_name: Firefox 04:13:54 INFO - mozversion application_remotingname: firefox-dev 04:13:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:54 INFO - mozversion application_vendor: Mozilla 04:13:54 INFO - mozversion application_version: 50.0a2 04:13:54 INFO - mozversion platform_buildid: 20160817151514 04:13:54 INFO - mozversion platform_changeset: 8ececbd9880dc48a879d7a0d44dbf5e15248b09c 04:13:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:13:54 INFO - mozversion platform_version: 50.0a2 04:13:54 INFO - using testdate: 1471518834 04:13:54 INFO - actual date: 1471518834 04:13:54 INFO - starting webserver on 'localhost:36641' 04:13:54 INFO - SUITE-START | Running 2 tests 04:13:54 INFO - TEST-START | dromaeo_css 04:13:54 INFO - operating with platform_type : linux_ 04:13:54 INFO - Initialising browser for dromaeo_css test... 04:13:54 INFO - TEST-INFO | started process 30698 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9UXGTm/profile http://localhost:36641/getInfo.html) 04:13:58 INFO - PROCESS | 30698 | __metrics Screen width/height:1600/1200 04:13:58 INFO - PROCESS | 30698 | colorDepth:24 04:13:58 INFO - PROCESS | 30698 | Browser inner width/height: 1024/697 04:13:58 INFO - PROCESS | 30698 | __metrics 04:13:59 INFO - PROCESS | 30698 | 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:59 INFO - PROCESS | 30698 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:14:00 INFO - TEST-INFO | 30698: exit 0 04:14:00 INFO - Browser initialized. 04:14:00 INFO - Running cycle 1/1 for dromaeo_css test... 04:14:00 INFO - TEST-INFO | started process 30792 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9UXGTm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:12 INFO - PROCESS | 30792 | RSS: Main: 144625664 04:14:12 INFO - PROCESS | 30792 | 04:15:03 INFO - PROCESS | 30792 | 1471518903515 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:12 INFO - PROCESS | 30792 | Cycle 1(1): loaded http://localhost:36641/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36641/tests/dromaeo/cssquery-ext.html) 04:17:13 INFO - PROCESS | 30792 | RSS: Main: 217493504 04:17:13 INFO - PROCESS | 30792 | 04:20:16 INFO - PROCESS | 30792 | Cycle 1(1): loaded http://localhost:36641/tests/dromaeo/cssquery-ext.html (next: http://localhost:36641/tests/dromaeo/cssquery-jquery.html) 04:20:16 INFO - PROCESS | 30792 | RSS: Main: 225136640 04:20:16 INFO - PROCESS | 30792 | 04:23:18 INFO - PROCESS | 30792 | Cycle 1(1): loaded http://localhost:36641/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36641/tests/dromaeo/cssquery-mootools.html) 04:23:19 INFO - PROCESS | 30792 | RSS: Main: 206286848 04:23:19 INFO - PROCESS | 30792 | 04:26:21 INFO - PROCESS | 30792 | Cycle 1(1): loaded http://localhost:36641/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36641/tests/dromaeo/cssquery-prototype.html) 04:26:21 INFO - PROCESS | 30792 | RSS: Main: 174809088 04:26:21 INFO - PROCESS | 30792 | 04:29:24 INFO - PROCESS | 30792 | Cycle 1(1): loaded http://localhost:36641/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36641/tests/dromaeo/cssquery-yui.html) 04:29:25 INFO - PROCESS | 30792 | RSS: Main: 172466176 04:29:25 INFO - PROCESS | 30792 | 04:32:27 INFO - PROCESS | 30792 | Cycle 1(1): loaded http://localhost:36641/tests/dromaeo/cssquery-yui.html (next: http://localhost:36641/tests/dromaeo/cssquery-dojo.html) 04:32:27 INFO - PROCESS | 30792 | RSS: Main: 263561216 04:32:27 INFO - PROCESS | 30792 | 04:32:27 INFO - PROCESS | 30792 | __start_tp_report 04:32:27 INFO - PROCESS | 30792 | _x_x_mozilla_page_load 04:32:27 INFO - PROCESS | 30792 | _x_x_mozilla_page_load_details 04:32:27 INFO - PROCESS | 30792 | |i|pagename|runs| 04:32:27 INFO - PROCESS | 30792 | |0;dojo.html;3222.8227447490385;3234.644189139194;3243.4972579250216;3255.088575198945;3283.1792051987004;5558.777648894044;5667.008273552133;5688.914666280009;5691.943080569193;5708.143778433232;5501.367342755584;5557.972210138953;5582.609217354787;5593.74828132734;5604.131359639252;5001.374828146481;5003.824866129687;5028.421731500869;5043.5965122790085;5081.339425874631;4884.658073934826;4983.476734942833;5010.72441015744;5015.9968006398685;5067.467915868836;5058.11482990477;5170.508801663836;5175.58595312376;5176.145935920568;5178.844634660947;5090.872728181788;5100.464451232641;5151.742412879378;5176.0683077046115;5197.882455272115;4708.882277943078;4891.951080489185;5005.849824505271;5017.046761115376;5072.365954255718;5600.52395546379;5601.467860553282;5610.438956104357;5616.213730077751;5624.268845050158;5980.073088671263;6018;6022.939770602282;6032.849178770522;6033.336333003366;5984.73068711909;5999.040153575407;6048.243969503812;6051.062085376773;6054.8183554493435;5637.548996080344;5653.265075540195;5654.236678048452;5654.264945557093;5664.433556644344;5938.2280303560265;6191.690415479208;6209.9379006210265;6215.285242197205;6216.285127210338;5861.3552509223955;5907.556933230025;5931.1992880960515;5995.340512543617;6030.065339872327;6074.088886666947;6081.5742897996715;6090.764331210262;6113.724882380304;6114.633122012693;5860.8534786629825;6064.117226947358;6072.544559158081;6077.392260773976;6114.541409394314;5553.444655534414;5920.763169473173;5939.3763654816485;5991.5206783457215;6084.30030546484;4566.657500687462;4625.283081122364;4628.051249493846;4629.606483448877;4653.046125544255;4011.3180759270995;4110.794460276974;4112.300908845504;4122.979385103055;4152.91694166121;5644.943550564524;5706.315242170883;5706.572007099484;5775.653460792366;5791.218185544982;6066.7269972850445;6070.666113363807;6095.299040610298;6122.785702500391;6131.877362452726;5553.361363443256;5602.943970560243;5760.135979603093;5768.134779782982;5774;5057.216131499624;5059.544640982255;5087.821926232564;5091.1854103343285;5107.489251074863;6288.339724328971;6298.212723409574;6299.307076221612;6304.873902521924;6310.021946598285;5758.481736643722;5954.166416701579;6062.181605483203;6068.393160683896;6069.878602428015;6159.907601385893;6252.999520076766;6261.154744109487;6262.812115636538;6315.684215789284;5933.228680271537;5976.432238937294;5986;5989.880202396015;6035.034394496859;6039.033754599243;6083.969580152071;6097.512199024156;6099.420555047263;6111.419415155553;5683.943160568507;5794.710264486843;5804.6807425592;5850.151727999331;5950.256217972754;5910.645361278337;5919.733611987384;5922.881542369042;5923;5928.4960778334325;5862.208601838697;5864.589478736448;5867.295924489089;5868.943590153643;5870.735816888336;5637.915431268616;5800.419958004166;5890.4993075589055;5891.263592050994;5991.2510936132985;5763.423657634371;5790.160426738015;5793.913091303718;5795.478406943395;5808.448197421239 04:32:27 INFO - PROCESS | 30792 | |1;ext.html;598.7575032111994;631.274034859911;641.5348872067751;667.0794303860674;689.2142957028991;35880.46179307309;47361.289580656274;47768.28347574789;47785.04429911404;51553.484465155336;49237;49572.50427495724;49788;49796;49812.25281620769;8377.204165604287;9271.67549135782;9391.107844754737;9427.764305892373;11043.944780276128;11188.272762270468;11322.60370887019;11322.660320190365;11337.546498140064;11343.886561134406;11402.429878506084;11464.426778661074;11471.942640286787;11480.71298217543;11482.540698372097;8589.312854971588;10933.890661093366;11201.887981120248;11320.886791132065;11370.886291137107;11177.83233251502;11267.830982535268;11269.887301126966;11279.887201127965;11286.435678216138;46073;46460.07079858418;46516.53483465156;46611.76694116541;46623.30065049027;3936.6457018868155;3980.323345031323;3984.3625019996953;4052.6960477963976;4076.5311989121037;34704.13239669003;34777.65222347795;34890.651093488996;35421;36379.4543081854;4539.387182730322;4629.282461218483;4697.577218050392;4737.289406589005;4808.903821923542;383309;384955;385063;386050;387730;37987;39612.80193599014;63112.684436577525;63362.683186583774;63590.68204658947;40946.590534094874;41053.58946410557;41064.79467602643;41193.382099268536;41208.793956030626;17473.38843140484;17612.471625851245;17619;17629.206685699173;17638.735418968725;13256.536021239403;13961.301934903215;14638.634034149018;14664.560063198121;14674.266286685623;9272.165505104438;9275.257979361635;9283.978762336137;9285;9344.90655093454;3539.64603539644;3562.412201986695;3577.874774382884;3591.856325746993;3607.2244467439627;1069.5721711315537;1086.293908959167;1121.2095472691897;1134.495149658394;1191.1483289448086;34924;35413.77257392329;35892.32006194004;36070;36909.446358304645;1138.9658310250707;1150.0799360511724;1168.5325869652206;1175.747214348919;1194.26552670109;17960.640787184184;18034.0441637864;18227.270909163486;18349;18399.26402943894;627044;635676;637575;637929;638644;62583.37416625866;63055.36944630495;63262.36737632657;63340;63573.364266356744;1390.450771945076;1472.3521650473758;1515.0455213215823;1515.515035188729;1535.2937648681732;34352.31295374128;35495.82252088723;35694.82152589221;35810.8209458951;36155.63844361628;54086.72956635192;54806.45193548014;54819.45180548143;54844;54886.45113548813;448634;449200;450144;450268;450714;30277.697223027488;49402;50641.493585063676;50721.74639126781;51814.740926295126;50382.99234015226;50575;50916.49083509117;55246;55713;46234.768826157;47346.76326618345;47437.28844067411;48272.5172748268;48910.51089489202;45621;47307;47964.7601761989;48731;49090.30003349707;45423;45509.54490455053;47470.28794568015;47592.76203619098;48128;38371.2325753489;41609.37585936274;42019.57980420157;42129.36805947852;42165.78917105395;31298.21754456157;31605;31831.681683182873;32020.35959280755;32251.51622725614 04:32:27 INFO - PROCESS | 30792 | |2;jquery.html;392.79574621196997;407.7696101702536;408.8998195442116;415.3976733736083;436.6005105328625;15347.3093710783;20102.899485502552;20470.795292047074;20494.38516844495;21871.781282187174;20815.687764683542;20819.479513012182;20858.165673373085;20861.26985555502;21103;5768.567357448187;5810.448007439297;6053;6090.695465226743;6188.381161883798;5981.910271345933;6122;6134.9079763803575;6158.846028849292;6176.72204750785;6154.907676384858;6203.2245969253845;6209.348018458042;6239.376062393747;6242.032484964838;6131.264248290211;6223.5332350073695;6232.968835155818;6676.132102826601;6745.898811517782;6033.245844269466;6035.547333949971;6047.5766696331275;6051.788187413464;6055;15101.169435681033;15337.539873803804;15371.077735335915;15486.380544778196;18481.1683474244;2415.7946574541184;2501.2246203676737;2509.874506274697;2573.5367633825904;2631.8026148038975;10859.565617375296;11074.87640091414;11097.223194376396;11097.611583594535;11759.941605255483;2105.0737675422765;2110.303599812058;2209.1494774511766;2209.6133176694016;2250.831187660932;83247.16752832454;84229.57885210596;84363.57818210931;84468.57765711193;85033.57483212545;9580.712578622653;10010.54952527138;10064.74838129038;11922.225055371546;12267.754644907052;14466.132032078109;14493.420263189566;14542.418303267963;14547.490837820626;14552.272386380639;8781.91218087811;8814.603342849587;9000.324975626854;9003.549822508849;9117.680881169128;9030.142136497023;9080.091990801;9190.759247501635;9424.104710052534;9501.334906556613;4475.843345482893;4486.214912390318;4510.481294651091;4519.186546421675;4580.862574122782;2092.2363337381744;2100.8634438761433;2111.693804398384;2128.574285142947;2151.4728891421387;898.9706785730302;1008.2337423558002;1029.6602121299952;1131.1629394248198;1154.5785788187252;19615.509612259808;20817.271395501084;21348.50560460784;21355.14579416806;21375.67936480954;975.2100798353357;1055.2613170780485;1059.7138772531373;1070.6092276319087;1122.4780477078198;11008.449577521093;11690.415479226005;11979.401029948467;12010.75978480443;12013.27920324766;457046;462002;489397.28323670867;494315;495690;95533.52233238789;96037.5198124005;96058.51970740102;96487;100424.49787751015;1027.1577306608658;1041.5000799616075;1112.1492058575197;1115.7322242661864;1130.5873356224918;18465.446036618923;18628.441146765617;19168;19261.13324900411;19271.51821204481;31166.37667246597;33191;44359.20330012878;45763.77118114388;46012.53987460083;60645;61016.694916525135;61029.694851525455;61704.382956169866;63630.68184659047;9094.636214551343;9265.305102117234;9369.437833729999;9532.809343812945;9686.07982241686;10107.292489525957;13149;13932.791008134684;14072.507462238773;14154.504592339219;11921.52313907434;12098.818517722417;12296.385180740748;12417.813732793835;12801.871981280068;11767.705807354885;12017.158798883995;12340.629781106582;12604.432800523815;12741.299228542519;12202.81695774582;12534.937325313314;12653.55712550093;12656.17734847232;12773.297468639314;9236.399634023766;9296.39573427725;9326.533673316442;9512.33413661037;9549.216027874652;11796.4691588877;12133.541940546902;12440.377981100728;12679.492820287085;12726.23642581448 04:32:27 INFO - PROCESS | 30792 | |3;mootools.html;1480.2524725013866;1554.2384231726453;1625.3579835964795;1830.789459212191;1932.8936908470027;8723.607437665305;9156.58795354209;9233.307501937365;9486.525673716322;9489.6204151834;9356.25149988001;9469.526523673823;9479.146876781087;9480.76298092548;9487.28845336599;2400.27991602519;2446.2538925627678;2447.2780529743677;2450.473148273121;2454.263720883737;2428.418583193458;2495.4510007798253;2515.8238923275376;2519.899204031846;2534.6324782906468;2438.817088718344;2440.1093600835657;2454.0551887523343;2455.373879660684;2461.2616215135477;2348.58899692554;2355.140373763581;2361.7992470640015;2367.396313939943;2396.1733202045266;2407.313915534082;2428.5021570577987;2433.5011322678806;2433.9634905476432;2438.183208625113;9369.250459963254;9386.90613093867;9397.342186046912;9472.431654100707;9506.762330941712;5587.7485513152005;5796.855078623026;5799.043157878945;5804.100364443518;5804.361520232771;3453.568303962005;3460.584729832411;3488.5464889564196;3493.877714279988;3506.7019303359243;1209.5282839692438;1222.2544248008796;1231.0766924806396;1240.875912408752;1257.8239046533402;13200.405981730844;13322.933385333012;13323.800142997767;13328.466861325536;13335.399907004112;4771.355866957916;4787.329773831666;4874.731889746027;4928.67963582366;4983.103041452501;10508.369497830156;10530.578776848994;10535.947320263349;10546.367217966947;10562.841557376645;5628.71856407178;5635.126555383922;5639.887202256014;5642.858928526737;5643.266375371176;4715.198416269174;5070.239464080418;5174.9482505174465;5225.503577160163;5243.947560524422;4150.5641907599875;4153.617933080569;4225.154969006211;4249.2988656871885;4256.170046840836;1256.3290823205298;1278.8721127887322;1294.5986744109357;1296.5526893221822;1297.3902265934996;841.6296829395046;854.7264875239988;856.8029353248663;1051.2588625019343;1075.6074032977906;6145.324014358417;6361.332060133712;6410.391012853772;6454.935450645434;6558.442532384801;529.4625954656027;536.2064145065248;540.284123536316;558.9692566908859;693.8924466707668;4325.199838029975;4507.076049409863;4519.909601807945;4549;4593.885152871205;12211.328376939353;12626.81059784104;12730.66328035543;12745.04412169091;12765.680857978625;11120.165987550967;11381.146414018982;11594.304341739522;12637.052221083271;12651.936740316423;8801.559922004002;8835.20483156519;9036.774080648036;9038.186563209343;9040.41237319572;6521.152250207632;6726.091977582963;6754.223264324665;6778.593284402951;6875.07186529812;11325.15061370384;11479.713007174887;11678.182527223013;11692.70768230801;11702.824457633313;10731.946340268249;11019.449027548751;11323.547058117583;11381.886181138083;11388.829167562659;8020.398470114648;8268.751937441886;8271.255586997198;8384.706535271236;8596;8891.288696904103;9109.134632209929;9263.675771347971;9396.906030939603;9432.905670943202;7960.721374751856;8164.877526837221;8403.159684031549;8405.411621186458;8597.95701021516;8025.07711613172;8199.836003279781;8210.753677389688;8441.873371899304;8449.66201351939;7731.7680469586;7747.690092396241;7756.146823849259;7773.494722842997;7833.882491762514;5569.470900264469;5594.16087586849;5595.748191331319;5613.550915926798;5746.9712651436475;5824.70876456167;5828.417158284137;5889.224372617962;6008.279006519071;6117.602355847033 04:32:27 INFO - PROCESS | 30792 | |4;prototype.html;347.6975031722401;352.32002235685115;354.3568423311688;359.27964431315223;359.4069784854987;5441.27936031984;5493;5506.862328441791;5509;5546.334439867222;5449.754761035753;5465.754041068151;5477.808276710316;5480.7533660985255;5483.7806487740445;3911.8630847920326;4039.9798001010026;4125.278076336629;4297.290946993742;4302.548232435581;4287.571242875703;4290.4422425084695;4291.613754762071;4305.095929854734;4309.698321117523;4280.400743895857;4282.443282373302;4285.849995250152;4286.185624731306;4298.226319262531;4093.812794289667;4208.284591619432;4258.552851950562;4295.441592592959;4306.181825453154;3804.5542693776188;3809.6190380961957;3819.083419979185;3825.8469661213517;3827.8468861245515;5302;5311.336082989626;5316.4683531646915;5319.893602127936;5334.626576139672;4763.928116173868;4845.75771211438;4873.8781530461665;4896.632752543538;4926.975365123151;5698;5704.458076482728;5704.461514625951;5712.171735098429;5712.857178570527;4173.144505376391;4214.346776249687;4219.3249080147025;4220.388043733641;4223.113146239262;5579.931037929164;5689.6344877229585;5748.396418376094;5860.912086318709;5908.763649454017;4260.508821912306;4277.251480990814;4278.572142785696;4288.807003684841;4290.592393722592;4299.376590394375;4309.780463061526;4325.870223893288;4332.761698106634;4343.326784348431;4588.9541104588525;4825.034993001414;4836.540528649773;4899.681520701143;4911.214205727067;5703.515201207861;5731.598788084877;5732;5735.283089613798;5752.856178595569;1469.7281003014477;1483.7403454395653;1484.487851691165;1487;1489.7392956232616;914.2392011078041;943.8159558886089;950.5674917912334;954.2652157838426;956.8564715292762;4889.462159162489;4916.410030796327;4942.5057494251005;4944.307796908436;4948.777305021283;5178.456262092502;5185.896282074337;5186.247994040843;5189.42916279209;5198.766055527511;4628.514006029386;4886.828960986349;4924;4927.5811556017425;4947.431045429749;2748.7526122649056;2752.9538775265273;2760.2961244882827;2770.3074231442138;2786.247713117447;5295.443978382292;5341.252224688546;5347.288867562421;5372.543333816591;5397.595180361489;5318.574514038867;5321.6540924839765;5596.796688711946;5979.491743201789;6028.487578555784;6103.6032657877095;6138.355472675394;6146.877062458815;6147.262328520606;6167.907481387782;5624.268845050117;5853.590248682552;5882.588218824641;5903.734331954986;5916.792912248051;5826.563007774519;5849.239598852122;5852.561057920758;5854.736536855937;5859.326177489473;5874.559408044499;5935.228420305332;6073.969630151821;6121.4490695837585;6126.448619624255;4239.830406783817;4246.44796176495;4250.723702959298;4252.723572967747;4264;5654.236678048411;5839.824805255849;5844.298684157929;5900.498457631149;5911.793087241926;5681.931756851254;5715.542756579547;5760.127958413616;5767.163761254678;5779.162021506774;5726.656400615976;5747.396523364986;5749.338826035059;5764.510016648464;5774.307083150049;5512.200730893917;5594.664320140804;5679.119736440858;5693.804301096816;5712.200291959046;5720.256366672306;5755.453231942959;5759.625624334572;5761.884762304647;5763.250777398911;5609.21470994053;5634.915476267777;5645.209670646195;5647.491725744702;5653.519450846559 04:32:27 INFO - PROCESS | 30792 | |5;yui.html;807.4509333653117;824.2416976381728;831.7130589946466;904.9140331668498;955.106974978395;16135.515934521969;16970.660586788257;17019.31922723091;17077.40229091982;17200.483985480423;15692;15832.762508562351;15851.92074039631;15854.920725396358;15873.285702143403;736.7053178728511;879.147227189625;899.8065415935572;905.7011186308503;914.3051281026435;903.3721563513361;906.3383729877161;906.7959709065474;907.7821322882527;907.8048219632777;778.9415793815458;978.3445091788483;978.9951050244739;979.647326962293;981.1954197558006;921.8340698674236;942.8067246214509;945.021902331084;947.369998950697;953.1469334945185;702.2415790945818;702.9613371264578;703.7818276334352;703.8310805406717;703.9225685174627;15542.844571554366;15610;15633.29650165734;15653.843461565353;15655.452059177987;14253.64365890861;15025.398984040625;15054.322555485029;15061.472848450252;15308.540743777705;13470.326483675777;13649.181049137083;13869.306534673327;14121;14126.576202713935;4644.396228490308;4743.810247590092;4792.760361981887;4803.975980120112;4804.25534042224;84913.57543212244;86638.5668071662;89195.55402223012;89274.5536272321;90177.54911225468;11784.528618855149;12302.138850280395;12309.50761969529;12484;12555;23447.531049379257;23531.411714706926;23552.76447235506;23560.410989725395;23569.88215058948;9401.76495587602;9419.387739796892;9429.434233945985;9497.857532137023;9823.852642210373;5137.845864624067;5314.601404894648;5353.330833645794;5435.076037073707;5437.184422336682;3673.577538583044;3773.283076215565;3831.425286207091;3835.6547910688173;3835.712321575893;565.4204440448491;651.9380658837403;655.0207439877319;655.5673255651343;658.229871050862;776.4409625069941;823.086374124721;834.2991886814983;845.1421806866036;845.564534264854;10243.641472548425;10693.19801014927;10998.560057597766;11052;11251.606193783178;693.9618320992394;742.9561466140082;785.2461636828708;807.5437377881478;829.8589239829244;5099.184130539096;5348.652337598043;5462.716261678503;5477.342718873681;5590.748416321276;71357;72025;95513;124367.70863377312;125925;107961.46019269853;109065.45467272772;109186;113605;114833.4258328703;93051.5347423272;93402;93499.5325023384;94268.11463770687;96515.51742241243;8930.374873758774;9261;9998.650047248311;10401.219908507044;10579.31234469757;54917.17624235719;55046;55235.61910952258;55736.72131639316;56343.43656563382;70115.29884701292;75056.62471687606;83331;84061.15938840533;84644;12515.499380024698;12718.300493472947;13696.413319814366;13955.302234888419;14298.428062877785;14764.778528321869;15098.924505377403;16363.509094727176;16844.242009109374;17074.914625426794;15175.924120379328;15821.208939552746;16089.275982580577;16589.585260368105;16926;13280.734385312046;14258.144511329354;14282.57152285433;15190;15848.841511585199;12878.613641590768;13129.671758206121;15047.247637618295;15853.841461585236;15912.204389780232;11519.596814111466;11982.460789264329;12110.576129835414;12306.507739690313;12335;12987.233024706798;13195.14231574983;13210.207387556777;13457;13559.796603050765 04:32:27 INFO - PROCESS | 30792 | __end_tp_report 04:32:27 INFO - PROCESS | 30792 | __start_cc_report 04:32:27 INFO - PROCESS | 30792 | _x_x_mozilla_cycle_collect,1003 04:32:27 INFO - PROCESS | 30792 | __end_cc_report 04:32:27 INFO - PROCESS | 30792 | __startTimestamp1471519947937__endTimestamp 04:32:27 INFO - PROCESS | 30792 | 04:32:27 INFO - PROCESS | 30792 | ------- Summary: start ------- 04:32:27 INFO - PROCESS | 30792 | Number of tests: 6 04:32:27 INFO - PROCESS | 30792 | 04:32:27 INFO - PROCESS | 30792 | [#0] dojo.html Cycles:165 Average:5585.88 Median:5794.71 stddev:654.01 (11.3%) stddev-sans-first:629.19 04:32:27 INFO - PROCESS | 30792 | Values: 3222.8 3234.6 3243.5 3255.1 3283.2 5558.8 5667.0 5688.9 5691.9 5708.1 5501.4 5558.0 5582.6 5593.7 5604.1 5001.4 5003.8 5028.4 5043.6 5081.3 4884.7 4983.5 5010.7 5016.0 5067.5 5058.1 5170.5 5175.6 5176.1 5178.8 5090.9 5100.5 5151.7 5176.1 5197.9 4708.9 4892.0 5005.8 5017.0 5072.4 5600.5 5601.5 5610.4 5616.2 5624.3 5980.1 6018.0 6022.9 6032.8 6033.3 5984.7 5999.0 6048.2 6051.1 6054.8 5637.5 5653.3 5654.2 5654.3 5664.4 5938.2 6191.7 6209.9 6215.3 6216.3 5861.4 5907.6 5931.2 5995.3 6030.1 6074.1 6081.6 6090.8 6113.7 6114.6 5860.9 6064.1 6072.5 6077.4 6114.5 5553.4 5920.8 5939.4 5991.5 6084.3 4566.7 4625.3 4628.1 4629.6 4653.0 4011.3 4110.8 4112.3 4123.0 4152.9 5644.9 5706.3 5706.6 5775.7 5791.2 6066.7 6070.7 6095.3 6122.8 6131.9 5553.4 5602.9 5760.1 5768.1 5774.0 5057.2 5059.5 5087.8 5091.2 5107.5 6288.3 6298.2 6299.3 6304.9 6310.0 5758.5 5954.2 6062.2 6068.4 6069.9 6159.9 6253.0 6261.2 6262.8 6315.7 5933.2 5976.4 5986.0 5989.9 6035.0 6039.0 6084.0 6097.5 6099.4 6111.4 5683.9 5794.7 5804.7 5850.2 5950.3 5910.6 5919.7 5922.9 5923.0 5928.5 5862.2 5864.6 5867.3 5868.9 5870.7 5637.9 5800.4 5890.5 5891.3 5991.3 5763.4 5790.2 5793.9 5795.5 5808.4 04:32:27 INFO - PROCESS | 30792 | 04:32:27 INFO - PROCESS | 30792 | [#1] ext.html Cycles:180 Average:66011.81 Median:41000.09 stddev:133286.36 (325.1%) stddev-sans-first:133569.78 04:32:27 INFO - PROCESS | 30792 | Values: 598.8 631.3 641.5 667.1 689.2 35880.5 47361.3 47768.3 47785.0 51553.5 49237.0 49572.5 49788.0 49796.0 49812.3 8377.2 9271.7 9391.1 9427.8 11043.9 11188.3 11322.6 11322.7 11337.5 11343.9 11402.4 11464.4 11471.9 11480.7 11482.5 8589.3 10933.9 11201.9 11320.9 11370.9 11177.8 11267.8 11269.9 11279.9 11286.4 46073.0 46460.1 46516.5 46611.8 46623.3 3936.6 3980.3 3984.4 4052.7 4076.5 34704.1 34777.7 34890.7 35421.0 36379.5 4539.4 4629.3 4697.6 4737.3 4808.9 383309.0 384955.0 385063.0 386050.0 387730.0 37987.0 39612.8 63112.7 63362.7 63590.7 40946.6 41053.6 41064.8 41193.4 41208.8 17473.4 17612.5 17619.0 17629.2 17638.7 13256.5 13961.3 14638.6 14664.6 14674.3 9272.2 9275.3 9284.0 9285.0 9344.9 3539.6 3562.4 3577.9 3591.9 3607.2 1069.6 1086.3 1121.2 1134.5 1191.1 34924.0 35413.8 35892.3 36070.0 36909.4 1139.0 1150.1 1168.5 1175.7 1194.3 17960.6 18034.0 18227.3 18349.0 18399.3 627044.0 635676.0 637575.0 637929.0 638644.0 62583.4 63055.4 63262.4 63340.0 63573.4 1390.5 1472.4 1515.0 1515.5 1535.3 34352.3 35495.8 35694.8 35810.8 36155.6 54086.7 54806.5 54819.5 54844.0 54886.5 448634.0 449200.0 450144.0 450268.0 450714.0 30277.7 49402.0 50641.5 50721.7 51814.7 50383.0 50575.0 50916.5 55246.0 55713.0 46234.8 47346.8 47437.3 48272.5 48910.5 45621.0 47307.0 47964.8 48731.0 49090.3 45423.0 45509.5 47470.3 47592.8 48128.0 38371.2 41609.4 42019.6 42129.4 42165.8 31298.2 31605.0 31831.7 32020.4 32251.5 04:32:27 INFO - PROCESS | 30792 | 04:32:27 INFO - PROCESS | 30792 | [#2] jquery.html Cycles:180 Average:29233.29 Median:2209.38 stddev:79345.78 (3591.3%) stddev-sans-first:79538.81 04:32:27 INFO - PROCESS | 30792 | Values: 392.8 407.8 408.9 415.4 436.6 15347.3 20102.9 20470.8 20494.4 21871.8 20815.7 20819.5 20858.2 20861.3 21103.0 5768.6 5810.4 6053.0 6090.7 6188.4 5981.9 6122.0 6134.9 6158.8 6176.7 6154.9 6203.2 6209.3 6239.4 6242.0 6131.3 6223.5 6233.0 6676.1 6745.9 6033.2 6035.5 6047.6 6051.8 6055.0 15101.2 15337.5 15371.1 15486.4 18481.2 2415.8 2501.2 2509.9 2573.5 2631.8 10859.6 11074.9 11097.2 11097.6 11759.9 2105.1 2110.3 2209.1 2209.6 2250.8 83247.2 84229.6 84363.6 84468.6 85033.6 9580.7 10010.5 10064.7 11922.2 12267.8 14466.1 14493.4 14542.4 14547.5 14552.3 8781.9 8814.6 9000.3 9003.5 9117.7 9030.1 9080.1 9190.8 9424.1 9501.3 4475.8 4486.2 4510.5 4519.2 4580.9 2092.2 2100.9 2111.7 2128.6 2151.5 899.0 1008.2 1029.7 1131.2 1154.6 19615.5 20817.3 21348.5 21355.1 21375.7 975.2 1055.3 1059.7 1070.6 1122.5 11008.4 11690.4 11979.4 12010.8 12013.3 457046.0 462002.0 489397.3 494315.0 495690.0 95533.5 96037.5 96058.5 96487.0 100424.5 1027.2 1041.5 1112.1 1115.7 1130.6 18465.4 18628.4 19168.0 19261.1 19271.5 31166.4 33191.0 44359.2 45763.8 46012.5 60645.0 61016.7 61029.7 61704.4 63630.7 9094.6 9265.3 9369.4 9532.8 9686.1 10107.3 13149.0 13932.8 14072.5 14154.5 11921.5 12098.8 12296.4 12417.8 12801.9 11767.7 12017.2 12340.6 12604.4 12741.3 12202.8 12534.9 12653.6 12656.2 12773.3 9236.4 9296.4 9326.5 9512.3 9549.2 11796.5 12133.5 12440.4 12679.5 12726.2 04:32:27 INFO - PROCESS | 30792 | 04:32:27 INFO - PROCESS | 30792 | [#3] mootools.html Cycles:180 Average:6280.52 Median:4901.71 stddev:3651.33 (74.5%) stddev-sans-first:3643.75 04:32:27 INFO - PROCESS | 30792 | Values: 1480.3 1554.2 1625.4 1830.8 1932.9 8723.6 9156.6 9233.3 9486.5 9489.6 9356.3 9469.5 9479.1 9480.8 9487.3 2400.3 2446.3 2447.3 2450.5 2454.3 2428.4 2495.5 2515.8 2519.9 2534.6 2438.8 2440.1 2454.1 2455.4 2461.3 2348.6 2355.1 2361.8 2367.4 2396.2 2407.3 2428.5 2433.5 2434.0 2438.2 9369.3 9386.9 9397.3 9472.4 9506.8 5587.7 5796.9 5799.0 5804.1 5804.4 3453.6 3460.6 3488.5 3493.9 3506.7 1209.5 1222.3 1231.1 1240.9 1257.8 13200.4 13322.9 13323.8 13328.5 13335.4 4771.4 4787.3 4874.7 4928.7 4983.1 10508.4 10530.6 10535.9 10546.4 10562.8 5628.7 5635.1 5639.9 5642.9 5643.3 4715.2 5070.2 5174.9 5225.5 5243.9 4150.6 4153.6 4225.2 4249.3 4256.2 1256.3 1278.9 1294.6 1296.6 1297.4 841.6 854.7 856.8 1051.3 1075.6 6145.3 6361.3 6410.4 6454.9 6558.4 529.5 536.2 540.3 559.0 693.9 4325.2 4507.1 4519.9 4549.0 4593.9 12211.3 12626.8 12730.7 12745.0 12765.7 11120.2 11381.1 11594.3 12637.1 12651.9 8801.6 8835.2 9036.8 9038.2 9040.4 6521.2 6726.1 6754.2 6778.6 6875.1 11325.2 11479.7 11678.2 11692.7 11702.8 10731.9 11019.4 11323.5 11381.9 11388.8 8020.4 8268.8 8271.3 8384.7 8596.0 8891.3 9109.1 9263.7 9396.9 9432.9 7960.7 8164.9 8403.2 8405.4 8598.0 8025.1 8199.8 8210.8 8441.9 8449.7 7731.8 7747.7 7756.1 7773.5 7833.9 5569.5 5594.2 5595.7 5613.6 5747.0 5824.7 5828.4 5889.2 6008.3 6117.6 04:32:27 INFO - PROCESS | 30792 | 04:32:27 INFO - PROCESS | 30792 | [#4] prototype.html Cycles:180 Average:4742.08 Median:5320.77 stddev:1387.47 (26.1%) stddev-sans-first:1351.59 04:32:27 INFO - PROCESS | 30792 | Values: 347.7 352.3 354.4 359.3 359.4 5441.3 5493.0 5506.9 5509.0 5546.3 5449.8 5465.8 5477.8 5480.8 5483.8 3911.9 4040.0 4125.3 4297.3 4302.5 4287.6 4290.4 4291.6 4305.1 4309.7 4280.4 4282.4 4285.8 4286.2 4298.2 4093.8 4208.3 4258.6 4295.4 4306.2 3804.6 3809.6 3819.1 3825.8 3827.8 5302.0 5311.3 5316.5 5319.9 5334.6 4763.9 4845.8 4873.9 4896.6 4927.0 5698.0 5704.5 5704.5 5712.2 5712.9 4173.1 4214.3 4219.3 4220.4 4223.1 5579.9 5689.6 5748.4 5860.9 5908.8 4260.5 4277.3 4278.6 4288.8 4290.6 4299.4 4309.8 4325.9 4332.8 4343.3 4589.0 4825.0 4836.5 4899.7 4911.2 5703.5 5731.6 5732.0 5735.3 5752.9 1469.7 1483.7 1484.5 1487.0 1489.7 914.2 943.8 950.6 954.3 956.9 4889.5 4916.4 4942.5 4944.3 4948.8 5178.5 5185.9 5186.2 5189.4 5198.8 4628.5 4886.8 4924.0 4927.6 4947.4 2748.8 2753.0 2760.3 2770.3 2786.2 5295.4 5341.3 5347.3 5372.5 5397.6 5318.6 5321.7 5596.8 5979.5 6028.5 6103.6 6138.4 6146.9 6147.3 6167.9 5624.3 5853.6 5882.6 5903.7 5916.8 5826.6 5849.2 5852.6 5854.7 5859.3 5874.6 5935.2 6074.0 6121.4 6126.4 4239.8 4246.4 4250.7 4252.7 4264.0 5654.2 5839.8 5844.3 5900.5 5911.8 5681.9 5715.5 5760.1 5767.2 5779.2 5726.7 5747.4 5749.3 5764.5 5774.3 5512.2 5594.7 5679.1 5693.8 5712.2 5720.3 5755.5 5759.6 5761.9 5763.3 5609.2 5634.9 5645.2 5647.5 5653.5 04:32:27 INFO - PROCESS | 30792 | 04:32:27 INFO - PROCESS | 30792 | [#5] yui.html Cycles:180 Average:22104.69 Median:4768.29 stddev:31116.28 (652.6%) stddev-sans-first:31162.48 04:32:27 INFO - PROCESS | 30792 | Values: 807.5 824.2 831.7 904.9 955.1 16135.5 16970.7 17019.3 17077.4 17200.5 15692.0 15832.8 15851.9 15854.9 15873.3 736.7 879.1 899.8 905.7 914.3 903.4 906.3 906.8 907.8 907.8 778.9 978.3 979.0 979.6 981.2 921.8 942.8 945.0 947.4 953.1 702.2 703.0 703.8 703.8 703.9 15542.8 15610.0 15633.3 15653.8 15655.5 14253.6 15025.4 15054.3 15061.5 15308.5 13470.3 13649.2 13869.3 14121.0 14126.6 4644.4 4743.8 4792.8 4804.0 4804.3 84913.6 86638.6 89195.6 89274.6 90177.5 11784.5 12302.1 12309.5 12484.0 12555.0 23447.5 23531.4 23552.8 23560.4 23569.9 9401.8 9419.4 9429.4 9497.9 9823.9 5137.8 5314.6 5353.3 5435.1 5437.2 3673.6 3773.3 3831.4 3835.7 3835.7 565.4 651.9 655.0 655.6 658.2 776.4 823.1 834.3 845.1 845.6 10243.6 10693.2 10998.6 11052.0 11251.6 694.0 743.0 785.2 807.5 829.9 5099.2 5348.7 5462.7 5477.3 5590.7 71357.0 72025.0 95513.0 124367.7 125925.0 107961.5 109065.5 109186.0 113605.0 114833.4 93051.5 93402.0 93499.5 94268.1 96515.5 8930.4 9261.0 9998.7 10401.2 10579.3 54917.2 55046.0 55235.6 55736.7 56343.4 70115.3 75056.6 83331.0 84061.2 84644.0 12515.5 12718.3 13696.4 13955.3 14298.4 14764.8 15098.9 16363.5 16844.2 17074.9 15175.9 15821.2 16089.3 16589.6 16926.0 13280.7 14258.1 14282.6 15190.0 15848.8 12878.6 13129.7 15047.2 15853.8 15912.2 11519.6 11982.5 12110.6 12306.5 12335.0 12987.2 13195.1 13210.2 13457.0 13559.8 04:32:27 INFO - PROCESS | 30792 | -------- Summary: end -------- 04:32:27 INFO - PROCESS | 30792 | 04:32:28 INFO - TEST-INFO | 30792: exit 0 04:32:28 INFO - TEST-OK | dromaeo_css | took 1114089ms 04:32:28 INFO - TEST-START | kraken 04:32:28 INFO - operating with platform_type : linux_ 04:32:28 INFO - Initialising browser for kraken test... 04:32:28 INFO - TEST-INFO | started process 30997 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb3gc4U/profile http://localhost:36641/getInfo.html) 04:32:32 INFO - PROCESS | 30997 | __metrics Screen width/height:1600/1200 04:32:32 INFO - PROCESS | 30997 | colorDepth:24 04:32:32 INFO - PROCESS | 30997 | Browser inner width/height: 1024/697 04:32:32 INFO - PROCESS | 30997 | __metrics 04:32:33 INFO - PROCESS | 30997 | 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:33 INFO - PROCESS | 30997 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:32:34 INFO - TEST-INFO | 30997: exit 0 04:32:34 INFO - Browser initialized. 04:32:34 INFO - Running cycle 1/1 for kraken test... 04:32:34 INFO - TEST-INFO | started process 31088 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb3gc4U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:32:46 INFO - PROCESS | 31088 | RSS: Main: 145068032 04:32:46 INFO - PROCESS | 31088 | 04:32:49 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36641/tests/kraken/driver.html?testName=audio-beat-detection) 04:32:49 INFO - PROCESS | 31088 | RSS: Main: 191614976 04:32:49 INFO - PROCESS | 31088 | 04:32:52 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36641/tests/kraken/driver.html?testName=audio-dft) 04:32:53 INFO - PROCESS | 31088 | RSS: Main: 182083584 04:32:53 INFO - PROCESS | 31088 | 04:32:55 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36641/tests/kraken/driver.html?testName=audio-fft) 04:32:56 INFO - PROCESS | 31088 | RSS: Main: 269398016 04:32:56 INFO - PROCESS | 31088 | 04:32:58 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36641/tests/kraken/driver.html?testName=audio-oscillator) 04:32:59 INFO - PROCESS | 31088 | RSS: Main: 175374336 04:32:59 INFO - PROCESS | 31088 | 04:33:01 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36641/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:33:01 INFO - PROCESS | 31088 | RSS: Main: 177504256 04:33:01 INFO - PROCESS | 31088 | 04:33:04 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36641/tests/kraken/driver.html?testName=imaging-darkroom) 04:33:05 INFO - PROCESS | 31088 | RSS: Main: 213118976 04:33:05 INFO - PROCESS | 31088 | 04:33:08 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36641/tests/kraken/driver.html?testName=imaging-desaturate) 04:33:09 INFO - PROCESS | 31088 | RSS: Main: 211763200 04:33:09 INFO - PROCESS | 31088 | 04:33:12 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36641/tests/kraken/driver.html?testName=json-parse-financial) 04:33:12 INFO - PROCESS | 31088 | RSS: Main: 210939904 04:33:12 INFO - PROCESS | 31088 | 04:33:14 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36641/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:33:14 INFO - PROCESS | 31088 | RSS: Main: 175931392 04:33:14 INFO - PROCESS | 31088 | 04:33:16 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36641/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:33:17 INFO - PROCESS | 31088 | RSS: Main: 182210560 04:33:17 INFO - PROCESS | 31088 | 04:33:19 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36641/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:33:19 INFO - PROCESS | 31088 | RSS: Main: 186265600 04:33:19 INFO - PROCESS | 31088 | 04:33:22 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36641/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:33:22 INFO - PROCESS | 31088 | RSS: Main: 180981760 04:33:22 INFO - PROCESS | 31088 | 04:33:25 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36641/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:33:26 INFO - PROCESS | 31088 | RSS: Main: 184242176 04:33:26 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | Cycle 1(1): loaded http://localhost:36641/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36641/tests/kraken/driver.html?testName=ai-astar) 04:33:27 INFO - PROCESS | 31088 | RSS: Main: 235069440 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | __start_tp_report 04:33:27 INFO - PROCESS | 31088 | _x_x_mozilla_page_load 04:33:27 INFO - PROCESS | 31088 | _x_x_mozilla_page_load_details 04:33:27 INFO - PROCESS | 31088 | |i|pagename|runs| 04:33:27 INFO - PROCESS | 31088 | |0;ai-astar;103;112;110;111;110;111;112;109;112;110 04:33:27 INFO - PROCESS | 31088 | |1;audio-beat-detection;152;115;117;177;116;122;142;119;161;117 04:33:27 INFO - PROCESS | 31088 | |2;audio-dft;162;159;160;161;160;160;160;159;160;159 04:33:27 INFO - PROCESS | 31088 | |3;audio-fft;75;75;74;112;84;76;75;74;105;73 04:33:27 INFO - PROCESS | 31088 | |4;audio-oscillator;82;83;124;83;84;84;112;83;83;131 04:33:27 INFO - PROCESS | 31088 | |5;imaging-gaussian-blur;117;116;117;116;117;116;116;116;116;117 04:33:27 INFO - PROCESS | 31088 | |6;imaging-darkroom;146;145;146;147;147;146;145;147;147;147 04:33:27 INFO - PROCESS | 31088 | |7;imaging-desaturate;95;95;94;95;95;95;95;95;94;95 04:33:27 INFO - PROCESS | 31088 | |8;json-parse-financial;52;52;51;51;52;54;51;53;51;50 04:33:27 INFO - PROCESS | 31088 | |9;json-stringify-tinderbox;59;62;59;59;60;60;60;60;60;60 04:33:27 INFO - PROCESS | 31088 | |10;stanford-crypto-aes;73;72;72;81;78;72;71;74;78;76 04:33:27 INFO - PROCESS | 31088 | |11;stanford-crypto-ccm;128;125;119;129;132;115;117;119;159;117 04:33:27 INFO - PROCESS | 31088 | |12;stanford-crypto-pbkdf2;155;154;153;155;152;153;154;157;150;152 04:33:27 INFO - PROCESS | 31088 | |13;stanford-crypto-sha256-iterative;61;61;62;62;61;63;61;61;60;60 04:33:27 INFO - PROCESS | 31088 | __end_tp_report 04:33:27 INFO - PROCESS | 31088 | __start_cc_report 04:33:27 INFO - PROCESS | 31088 | _x_x_mozilla_cycle_collect,1627 04:33:27 INFO - PROCESS | 31088 | __end_cc_report 04:33:27 INFO - PROCESS | 31088 | __startTimestamp1471520007943__endTimestamp 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | ------- Summary: start ------- 04:33:27 INFO - PROCESS | 31088 | Number of tests: 14 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:2.67 (2.4%) stddev-sans-first:1.09 04:33:27 INFO - PROCESS | 31088 | Values: 103.0 112.0 110.0 111.0 110.0 111.0 112.0 109.0 112.0 110.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:132.00 stddev:22.60 (17.1%) stddev-sans-first:22.99 04:33:27 INFO - PROCESS | 31088 | Values: 152.0 115.0 117.0 177.0 116.0 122.0 142.0 119.0 161.0 117.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.94 (0.6%) stddev-sans-first:0.67 04:33:27 INFO - PROCESS | 31088 | Values: 162.0 159.0 160.0 161.0 160.0 160.0 160.0 159.0 160.0 159.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#3] audio-fft Cycles:10 Average:82.30 Median:75.00 stddev:14.24 (19.0%) stddev-sans-first:14.85 04:33:27 INFO - PROCESS | 31088 | Values: 75.0 75.0 74.0 112.0 84.0 76.0 75.0 74.0 105.0 73.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:83.00 stddev:19.47 (23.5%) stddev-sans-first:20.09 04:33:27 INFO - PROCESS | 31088 | Values: 82.0 83.0 124.0 83.0 84.0 84.0 112.0 83.0 83.0 131.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 04:33:27 INFO - PROCESS | 31088 | Values: 117.0 116.0 117.0 116.0 117.0 116.0 116.0 116.0 116.0 117.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 04:33:27 INFO - PROCESS | 31088 | Values: 146.0 145.0 146.0 147.0 147.0 146.0 145.0 147.0 147.0 147.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:33:27 INFO - PROCESS | 31088 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:1.16 (2.2%) stddev-sans-first:1.22 04:33:27 INFO - PROCESS | 31088 | Values: 52.0 52.0 51.0 51.0 52.0 54.0 51.0 53.0 51.0 50.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.88 (1.5%) stddev-sans-first:0.87 04:33:27 INFO - PROCESS | 31088 | Values: 59.0 62.0 59.0 59.0 60.0 60.0 60.0 60.0 60.0 60.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:75.00 stddev:3.37 (4.5%) stddev-sans-first:3.52 04:33:27 INFO - PROCESS | 31088 | Values: 73.0 72.0 72.0 81.0 78.0 72.0 71.0 74.0 78.0 76.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:126.50 stddev:13.00 (10.3%) stddev-sans-first:13.76 04:33:27 INFO - PROCESS | 31088 | Values: 128.0 125.0 119.0 129.0 132.0 115.0 117.0 119.0 159.0 117.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.50 Median:154.00 stddev:1.96 (1.3%) stddev-sans-first:2.00 04:33:27 INFO - PROCESS | 31088 | Values: 155.0 154.0 153.0 155.0 152.0 153.0 154.0 157.0 150.0 152.0 04:33:27 INFO - PROCESS | 31088 | 04:33:27 INFO - PROCESS | 31088 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 04:33:27 INFO - PROCESS | 31088 | Values: 61.0 61.0 62.0 62.0 61.0 63.0 61.0 61.0 60.0 60.0 04:33:27 INFO - PROCESS | 31088 | -------- Summary: end -------- 04:33:27 INFO - PROCESS | 31088 | 04:33:28 INFO - TEST-INFO | 31088: exit 0 04:33:28 INFO - TEST-OK | kraken | took 60249ms 04:33:28 INFO - SUITE-END | took 1174s 04:33:29 INFO - Completed test suite (00:19:34) 04:33:29 INFO - javascript benchmark 04:33:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3222.8227447490385, 3234.644189139194, 3243.4972579250216, 3255.088575198945, 3283.1792051987004, 5558.777648894044, 5667.008273552133, 5688.914666280009, 5691.943080569193, 5708.143778433232, 5501.367342755584, 5557.972210138953, 5582.609217354787, 5593.74828132734, 5604.131359639252, 5001.374828146481, 5003.824866129687, 5028.421731500869, 5043.5965122790085, 5081.339425874631, 4884.658073934826, 4983.476734942833, 5010.72441015744, 5015.9968006398685, 5067.467915868836, 5058.11482990477, 5170.508801663836, 5175.58595312376, 5176.145935920568, 5178.844634660947, 5090.872728181788, 5100.464451232641, 5151.742412879378, 5176.0683077046115, 5197.882455272115, 4708.882277943078, 4891.951080489185, 5005.849824505271, 5017.046761115376, 5072.365954255718, 5600.52395546379, 5601.467860553282, 5610.438956104357, 5616.213730077751, 5624.268845050158, 5980.073088671263, 6018.0, 6022.939770602282, 6032.849178770522, 6033.336333003366, 5984.73068711909, 5999.040153575407, 6048.243969503812, 6051.062085376773, 6054.8183554493435, 5637.548996080344, 5653.265075540195, 5654.236678048452, 5654.264945557093, 5664.433556644344, 5938.2280303560265, 6191.690415479208, 6209.9379006210265, 6215.285242197205, 6216.285127210338, 5861.3552509223955, 5907.556933230025, 5931.1992880960515, 5995.340512543617, 6030.065339872327, 6074.088886666947, 6081.5742897996715, 6090.764331210262, 6113.724882380304, 6114.633122012693, 5860.8534786629825, 6064.117226947358, 6072.544559158081, 6077.392260773976, 6114.541409394314, 5553.444655534414, 5920.763169473173, 5939.3763654816485, 5991.5206783457215, 6084.30030546484, 4566.657500687462, 4625.283081122364, 4628.051249493846, 4629.606483448877, 4653.046125544255, 4011.3180759270995, 4110.794460276974, 4112.300908845504, 4122.979385103055, 4152.91694166121, 5644.943550564524, 5706.315242170883, 5706.572007099484, 5775.653460792366, 5791.218185544982, 6066.7269972850445, 6070.666113363807, 6095.299040610298, 6122.785702500391, 6131.877362452726, 5553.361363443256, 5602.943970560243, 5760.135979603093, 5768.134779782982, 5774.0, 5057.216131499624, 5059.544640982255, 5087.821926232564, 5091.1854103343285, 5107.489251074863, 6288.339724328971, 6298.212723409574, 6299.307076221612, 6304.873902521924, 6310.021946598285, 5758.481736643722, 5954.166416701579, 6062.181605483203, 6068.393160683896, 6069.878602428015, 6159.907601385893, 6252.999520076766, 6261.154744109487, 6262.812115636538, 6315.684215789284, 5933.228680271537, 5976.432238937294, 5986.0, 5989.880202396015, 6035.034394496859, 6039.033754599243, 6083.969580152071, 6097.512199024156, 6099.420555047263, 6111.419415155553, 5683.943160568507, 5794.710264486843, 5804.6807425592, 5850.151727999331, 5950.256217972754, 5910.645361278337, 5919.733611987384, 5922.881542369042, 5923.0, 5928.4960778334325, 5862.208601838697, 5864.589478736448, 5867.295924489089, 5868.943590153643, 5870.735816888336, 5637.915431268616, 5800.419958004166, 5890.4993075589055, 5891.263592050994, 5991.2510936132985, 5763.423657634371, 5790.160426738015, 5793.913091303718, 5795.478406943395, 5808.448197421239], "value": 5540.846615022466, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.7575032111994, 631.274034859911, 641.5348872067751, 667.0794303860674, 689.2142957028991, 35880.46179307309, 47361.289580656274, 47768.28347574789, 47785.04429911404, 51553.484465155336, 49237.0, 49572.50427495724, 49788.0, 49796.0, 49812.25281620769, 8377.204165604287, 9271.67549135782, 9391.107844754737, 9427.764305892373, 11043.944780276128, 11188.272762270468, 11322.60370887019, 11322.660320190365, 11337.546498140064, 11343.886561134406, 11402.429878506084, 11464.426778661074, 11471.942640286787, 11480.71298217543, 11482.540698372097, 8589.312854971588, 10933.890661093366, 11201.887981120248, 11320.886791132065, 11370.886291137107, 11177.83233251502, 11267.830982535268, 11269.887301126966, 11279.887201127965, 11286.435678216138, 46073.0, 46460.07079858418, 46516.53483465156, 46611.76694116541, 46623.30065049027, 3936.6457018868155, 3980.323345031323, 3984.3625019996953, 4052.6960477963976, 4076.5311989121037, 34704.13239669003, 34777.65222347795, 34890.651093488996, 35421.0, 36379.4543081854, 4539.387182730322, 4629.282461218483, 4697.577218050392, 4737.289406589005, 4808.903821923542, 383309.0, 384955.0, 385063.0, 386050.0, 387730.0, 37987.0, 39612.80193599014, 63112.684436577525, 63362.683186583774, 63590.68204658947, 40946.590534094874, 41053.58946410557, 41064.79467602643, 41193.382099268536, 41208.793956030626, 17473.38843140484, 17612.471625851245, 17619.0, 17629.206685699173, 17638.735418968725, 13256.536021239403, 13961.301934903215, 14638.634034149018, 14664.560063198121, 14674.266286685623, 9272.165505104438, 9275.257979361635, 9283.978762336137, 9285.0, 9344.90655093454, 3539.64603539644, 3562.412201986695, 3577.874774382884, 3591.856325746993, 3607.2244467439627, 1069.5721711315537, 1086.293908959167, 1121.2095472691897, 1134.495149658394, 1191.1483289448086, 34924.0, 35413.77257392329, 35892.32006194004, 36070.0, 36909.446358304645, 1138.9658310250707, 1150.0799360511724, 1168.5325869652206, 1175.747214348919, 1194.26552670109, 17960.640787184184, 18034.0441637864, 18227.270909163486, 18349.0, 18399.26402943894, 627044.0, 635676.0, 637575.0, 637929.0, 638644.0, 62583.37416625866, 63055.36944630495, 63262.36737632657, 63340.0, 63573.364266356744, 1390.450771945076, 1472.3521650473758, 1515.0455213215823, 1515.515035188729, 1535.2937648681732, 34352.31295374128, 35495.82252088723, 35694.82152589221, 35810.8209458951, 36155.63844361628, 54086.72956635192, 54806.45193548014, 54819.45180548143, 54844.0, 54886.45113548813, 448634.0, 449200.0, 450144.0, 450268.0, 450714.0, 30277.697223027488, 49402.0, 50641.493585063676, 50721.74639126781, 51814.740926295126, 50382.99234015226, 50575.0, 50916.49083509117, 55246.0, 55713.0, 46234.768826157, 47346.76326618345, 47437.28844067411, 48272.5172748268, 48910.51089489202, 45621.0, 47307.0, 47964.7601761989, 48731.0, 49090.30003349707, 45423.0, 45509.54490455053, 47470.28794568015, 47592.76203619098, 48128.0, 38371.2325753489, 41609.37585936274, 42019.57980420157, 42129.36805947852, 42165.78917105395, 31298.21754456157, 31605.0, 31831.681683182873, 32020.35959280755, 32251.51622725614], "value": 21349.918831079962, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.79574621196997, 407.7696101702536, 408.8998195442116, 415.3976733736083, 436.6005105328625, 15347.3093710783, 20102.899485502552, 20470.795292047074, 20494.38516844495, 21871.781282187174, 20815.687764683542, 20819.479513012182, 20858.165673373085, 20861.26985555502, 21103.0, 5768.567357448187, 5810.448007439297, 6053.0, 6090.695465226743, 6188.381161883798, 5981.910271345933, 6122.0, 6134.9079763803575, 6158.846028849292, 6176.72204750785, 6154.907676384858, 6203.2245969253845, 6209.348018458042, 6239.376062393747, 6242.032484964838, 6131.264248290211, 6223.5332350073695, 6232.968835155818, 6676.132102826601, 6745.898811517782, 6033.245844269466, 6035.547333949971, 6047.5766696331275, 6051.788187413464, 6055.0, 15101.169435681033, 15337.539873803804, 15371.077735335915, 15486.380544778196, 18481.1683474244, 2415.7946574541184, 2501.2246203676737, 2509.874506274697, 2573.5367633825904, 2631.8026148038975, 10859.565617375296, 11074.87640091414, 11097.223194376396, 11097.611583594535, 11759.941605255483, 2105.0737675422765, 2110.303599812058, 2209.1494774511766, 2209.6133176694016, 2250.831187660932, 83247.16752832454, 84229.57885210596, 84363.57818210931, 84468.57765711193, 85033.57483212545, 9580.712578622653, 10010.54952527138, 10064.74838129038, 11922.225055371546, 12267.754644907052, 14466.132032078109, 14493.420263189566, 14542.418303267963, 14547.490837820626, 14552.272386380639, 8781.91218087811, 8814.603342849587, 9000.324975626854, 9003.549822508849, 9117.680881169128, 9030.142136497023, 9080.091990801, 9190.759247501635, 9424.104710052534, 9501.334906556613, 4475.843345482893, 4486.214912390318, 4510.481294651091, 4519.186546421675, 4580.862574122782, 2092.2363337381744, 2100.8634438761433, 2111.693804398384, 2128.574285142947, 2151.4728891421387, 898.9706785730302, 1008.2337423558002, 1029.6602121299952, 1131.1629394248198, 1154.5785788187252, 19615.509612259808, 20817.271395501084, 21348.50560460784, 21355.14579416806, 21375.67936480954, 975.2100798353357, 1055.2613170780485, 1059.7138772531373, 1070.6092276319087, 1122.4780477078198, 11008.449577521093, 11690.415479226005, 11979.401029948467, 12010.75978480443, 12013.27920324766, 457046.0, 462002.0, 489397.28323670867, 494315.0, 495690.0, 95533.52233238789, 96037.5198124005, 96058.51970740102, 96487.0, 100424.49787751015, 1027.1577306608658, 1041.5000799616075, 1112.1492058575197, 1115.7322242661864, 1130.5873356224918, 18465.446036618923, 18628.441146765617, 19168.0, 19261.13324900411, 19271.51821204481, 31166.37667246597, 33191.0, 44359.20330012878, 45763.77118114388, 46012.53987460083, 60645.0, 61016.694916525135, 61029.694851525455, 61704.382956169866, 63630.68184659047, 9094.636214551343, 9265.305102117234, 9369.437833729999, 9532.809343812945, 9686.07982241686, 10107.292489525957, 13149.0, 13932.791008134684, 14072.507462238773, 14154.504592339219, 11921.52313907434, 12098.818517722417, 12296.385180740748, 12417.813732793835, 12801.871981280068, 11767.705807354885, 12017.158798883995, 12340.629781106582, 12604.432800523815, 12741.299228542519, 12202.81695774582, 12534.937325313314, 12653.55712550093, 12656.17734847232, 12773.297468639314, 9236.399634023766, 9296.39573427725, 9326.533673316442, 9512.33413661037, 9549.216027874652, 11796.4691588877, 12133.541940546902, 12440.377981100728, 12679.492820287085, 12726.23642581448], "value": 9637.726110228328, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1480.2524725013866, 1554.2384231726453, 1625.3579835964795, 1830.789459212191, 1932.8936908470027, 8723.607437665305, 9156.58795354209, 9233.307501937365, 9486.525673716322, 9489.6204151834, 9356.25149988001, 9469.526523673823, 9479.146876781087, 9480.76298092548, 9487.28845336599, 2400.27991602519, 2446.2538925627678, 2447.2780529743677, 2450.473148273121, 2454.263720883737, 2428.418583193458, 2495.4510007798253, 2515.8238923275376, 2519.899204031846, 2534.6324782906468, 2438.817088718344, 2440.1093600835657, 2454.0551887523343, 2455.373879660684, 2461.2616215135477, 2348.58899692554, 2355.140373763581, 2361.7992470640015, 2367.396313939943, 2396.1733202045266, 2407.313915534082, 2428.5021570577987, 2433.5011322678806, 2433.9634905476432, 2438.183208625113, 9369.250459963254, 9386.90613093867, 9397.342186046912, 9472.431654100707, 9506.762330941712, 5587.7485513152005, 5796.855078623026, 5799.043157878945, 5804.100364443518, 5804.361520232771, 3453.568303962005, 3460.584729832411, 3488.5464889564196, 3493.877714279988, 3506.7019303359243, 1209.5282839692438, 1222.2544248008796, 1231.0766924806396, 1240.875912408752, 1257.8239046533402, 13200.405981730844, 13322.933385333012, 13323.800142997767, 13328.466861325536, 13335.399907004112, 4771.355866957916, 4787.329773831666, 4874.731889746027, 4928.67963582366, 4983.103041452501, 10508.369497830156, 10530.578776848994, 10535.947320263349, 10546.367217966947, 10562.841557376645, 5628.71856407178, 5635.126555383922, 5639.887202256014, 5642.858928526737, 5643.266375371176, 4715.198416269174, 5070.239464080418, 5174.9482505174465, 5225.503577160163, 5243.947560524422, 4150.5641907599875, 4153.617933080569, 4225.154969006211, 4249.2988656871885, 4256.170046840836, 1256.3290823205298, 1278.8721127887322, 1294.5986744109357, 1296.5526893221822, 1297.3902265934996, 841.6296829395046, 854.7264875239988, 856.8029353248663, 1051.2588625019343, 1075.6074032977906, 6145.324014358417, 6361.332060133712, 6410.391012853772, 6454.935450645434, 6558.442532384801, 529.4625954656027, 536.2064145065248, 540.284123536316, 558.9692566908859, 693.8924466707668, 4325.199838029975, 4507.076049409863, 4519.909601807945, 4549.0, 4593.885152871205, 12211.328376939353, 12626.81059784104, 12730.66328035543, 12745.04412169091, 12765.680857978625, 11120.165987550967, 11381.146414018982, 11594.304341739522, 12637.052221083271, 12651.936740316423, 8801.559922004002, 8835.20483156519, 9036.774080648036, 9038.186563209343, 9040.41237319572, 6521.152250207632, 6726.091977582963, 6754.223264324665, 6778.593284402951, 6875.07186529812, 11325.15061370384, 11479.713007174887, 11678.182527223013, 11692.70768230801, 11702.824457633313, 10731.946340268249, 11019.449027548751, 11323.547058117583, 11381.886181138083, 11388.829167562659, 8020.398470114648, 8268.751937441886, 8271.255586997198, 8384.706535271236, 8596.0, 8891.288696904103, 9109.134632209929, 9263.675771347971, 9396.906030939603, 9432.905670943202, 7960.721374751856, 8164.877526837221, 8403.159684031549, 8405.411621186458, 8597.95701021516, 8025.07711613172, 8199.836003279781, 8210.753677389688, 8441.873371899304, 8449.66201351939, 7731.7680469586, 7747.690092396241, 7756.146823849259, 7773.494722842997, 7833.882491762514, 5569.470900264469, 5594.16087586849, 5595.748191331319, 5613.550915926798, 5746.9712651436475, 5824.70876456167, 5828.417158284137, 5889.224372617962, 6008.279006519071, 6117.602355847033], "value": 4910.876016971872, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.6975031722401, 352.32002235685115, 354.3568423311688, 359.27964431315223, 359.4069784854987, 5441.27936031984, 5493.0, 5506.862328441791, 5509.0, 5546.334439867222, 5449.754761035753, 5465.754041068151, 5477.808276710316, 5480.7533660985255, 5483.7806487740445, 3911.8630847920326, 4039.9798001010026, 4125.278076336629, 4297.290946993742, 4302.548232435581, 4287.571242875703, 4290.4422425084695, 4291.613754762071, 4305.095929854734, 4309.698321117523, 4280.400743895857, 4282.443282373302, 4285.849995250152, 4286.185624731306, 4298.226319262531, 4093.812794289667, 4208.284591619432, 4258.552851950562, 4295.441592592959, 4306.181825453154, 3804.5542693776188, 3809.6190380961957, 3819.083419979185, 3825.8469661213517, 3827.8468861245515, 5302.0, 5311.336082989626, 5316.4683531646915, 5319.893602127936, 5334.626576139672, 4763.928116173868, 4845.75771211438, 4873.8781530461665, 4896.632752543538, 4926.975365123151, 5698.0, 5704.458076482728, 5704.461514625951, 5712.171735098429, 5712.857178570527, 4173.144505376391, 4214.346776249687, 4219.3249080147025, 4220.388043733641, 4223.113146239262, 5579.931037929164, 5689.6344877229585, 5748.396418376094, 5860.912086318709, 5908.763649454017, 4260.508821912306, 4277.251480990814, 4278.572142785696, 4288.807003684841, 4290.592393722592, 4299.376590394375, 4309.780463061526, 4325.870223893288, 4332.761698106634, 4343.326784348431, 4588.9541104588525, 4825.034993001414, 4836.540528649773, 4899.681520701143, 4911.214205727067, 5703.515201207861, 5731.598788084877, 5732.0, 5735.283089613798, 5752.856178595569, 1469.7281003014477, 1483.7403454395653, 1484.487851691165, 1487.0, 1489.7392956232616, 914.2392011078041, 943.8159558886089, 950.5674917912334, 954.2652157838426, 956.8564715292762, 4889.462159162489, 4916.410030796327, 4942.5057494251005, 4944.307796908436, 4948.777305021283, 5178.456262092502, 5185.896282074337, 5186.247994040843, 5189.42916279209, 5198.766055527511, 4628.514006029386, 4886.828960986349, 4924.0, 4927.5811556017425, 4947.431045429749, 2748.7526122649056, 2752.9538775265273, 2760.2961244882827, 2770.3074231442138, 2786.247713117447, 5295.443978382292, 5341.252224688546, 5347.288867562421, 5372.543333816591, 5397.595180361489, 5318.574514038867, 5321.6540924839765, 5596.796688711946, 5979.491743201789, 6028.487578555784, 6103.6032657877095, 6138.355472675394, 6146.877062458815, 6147.262328520606, 6167.907481387782, 5624.268845050117, 5853.590248682552, 5882.588218824641, 5903.734331954986, 5916.792912248051, 5826.563007774519, 5849.239598852122, 5852.561057920758, 5854.736536855937, 5859.326177489473, 5874.559408044499, 5935.228420305332, 6073.969630151821, 6121.4490695837585, 6126.448619624255, 4239.830406783817, 4246.44796176495, 4250.723702959298, 4252.723572967747, 4264.0, 5654.236678048411, 5839.824805255849, 5844.298684157929, 5900.498457631149, 5911.793087241926, 5681.931756851254, 5715.542756579547, 5760.127958413616, 5767.163761254678, 5779.162021506774, 5726.656400615976, 5747.396523364986, 5749.338826035059, 5764.510016648464, 5774.307083150049, 5512.200730893917, 5594.664320140804, 5679.119736440858, 5693.804301096816, 5712.200291959046, 5720.256366672306, 5755.453231942959, 5759.625624334572, 5761.884762304647, 5763.250777398911, 5609.21470994053, 5634.915476267777, 5645.209670646195, 5647.491725744702, 5653.519450846559], "value": 4304.047342391295, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [807.4509333653117, 824.2416976381728, 831.7130589946466, 904.9140331668498, 955.106974978395, 16135.515934521969, 16970.660586788257, 17019.31922723091, 17077.40229091982, 17200.483985480423, 15692.0, 15832.762508562351, 15851.92074039631, 15854.920725396358, 15873.285702143403, 736.7053178728511, 879.147227189625, 899.8065415935572, 905.7011186308503, 914.3051281026435, 903.3721563513361, 906.3383729877161, 906.7959709065474, 907.7821322882527, 907.8048219632777, 778.9415793815458, 978.3445091788483, 978.9951050244739, 979.647326962293, 981.1954197558006, 921.8340698674236, 942.8067246214509, 945.021902331084, 947.369998950697, 953.1469334945185, 702.2415790945818, 702.9613371264578, 703.7818276334352, 703.8310805406717, 703.9225685174627, 15542.844571554366, 15610.0, 15633.29650165734, 15653.843461565353, 15655.452059177987, 14253.64365890861, 15025.398984040625, 15054.322555485029, 15061.472848450252, 15308.540743777705, 13470.326483675777, 13649.181049137083, 13869.306534673327, 14121.0, 14126.576202713935, 4644.396228490308, 4743.810247590092, 4792.760361981887, 4803.975980120112, 4804.25534042224, 84913.57543212244, 86638.5668071662, 89195.55402223012, 89274.5536272321, 90177.54911225468, 11784.528618855149, 12302.138850280395, 12309.50761969529, 12484.0, 12555.0, 23447.531049379257, 23531.411714706926, 23552.76447235506, 23560.410989725395, 23569.88215058948, 9401.76495587602, 9419.387739796892, 9429.434233945985, 9497.857532137023, 9823.852642210373, 5137.845864624067, 5314.601404894648, 5353.330833645794, 5435.076037073707, 5437.184422336682, 3673.577538583044, 3773.283076215565, 3831.425286207091, 3835.6547910688173, 3835.712321575893, 565.4204440448491, 651.9380658837403, 655.0207439877319, 655.5673255651343, 658.229871050862, 776.4409625069941, 823.086374124721, 834.2991886814983, 845.1421806866036, 845.564534264854, 10243.641472548425, 10693.19801014927, 10998.560057597766, 11052.0, 11251.606193783178, 693.9618320992394, 742.9561466140082, 785.2461636828708, 807.5437377881478, 829.8589239829244, 5099.184130539096, 5348.652337598043, 5462.716261678503, 5477.342718873681, 5590.748416321276, 71357.0, 72025.0, 95513.0, 124367.70863377312, 125925.0, 107961.46019269853, 109065.45467272772, 109186.0, 113605.0, 114833.4258328703, 93051.5347423272, 93402.0, 93499.5325023384, 94268.11463770687, 96515.51742241243, 8930.374873758774, 9261.0, 9998.650047248311, 10401.219908507044, 10579.31234469757, 54917.17624235719, 55046.0, 55235.61910952258, 55736.72131639316, 56343.43656563382, 70115.29884701292, 75056.62471687606, 83331.0, 84061.15938840533, 84644.0, 12515.499380024698, 12718.300493472947, 13696.413319814366, 13955.302234888419, 14298.428062877785, 14764.778528321869, 15098.924505377403, 16363.509094727176, 16844.242009109374, 17074.914625426794, 15175.924120379328, 15821.208939552746, 16089.275982580577, 16589.585260368105, 16926.0, 13280.734385312046, 14258.144511329354, 14282.57152285433, 15190.0, 15848.841511585199, 12878.613641590768, 13129.671758206121, 15047.247637618295, 15853.841461585236, 15912.204389780232, 11519.596814111466, 11982.460789264329, 12110.576129835414, 12306.507739690313, 12335.0, 12987.233024706798, 13195.14231574983, 13210.207387556777, 13457.0, 13559.796603050765], "value": 8268.338876350685, "unit": "score"}], "name": "dromaeo_css", "value": 7642.597411257779, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 110.0, 111.0, 110.0, 111.0, 112.0, 109.0, 112.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 117.0, 177.0, 116.0, 122.0, 142.0, 119.0, 161.0, 117.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 160.0, 161.0, 160.0, 160.0, 160.0, 159.0, 160.0, 159.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 112.0, 84.0, 76.0, 75.0, 74.0, 105.0, 73.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 124.0, 83.0, 84.0, 84.0, 112.0, 83.0, 83.0, 131.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 147.0, 147.0, 146.0, 145.0, 147.0, 147.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 51.0, 51.0, 52.0, 54.0, 51.0, 53.0, 51.0, 50.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 59.0, 59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 81.0, 78.0, 72.0, 71.0, 74.0, 78.0, 76.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 125.0, 119.0, 129.0, 132.0, 115.0, 117.0, 119.0, 159.0, 117.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 154.0, 153.0, 155.0, 152.0, 153.0, 154.0, 157.0, 150.0, 152.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 62.0, 61.0, 63.0, 61.0, 61.0, 60.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1465.5000000000002, "alertThreshold": 2.0}]} 04:33:29 INFO - Return code: 0 04:33:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:33:29 INFO - # TBPL SUCCESS # 04:33:29 INFO - Running post-action listener: _resource_record_post_action 04:33:29 INFO - [mozharness: 2016-08-18 11:33:29.244886Z] Finished run-tests step (success) 04:33:29 INFO - Running post-run listener: _resource_record_post_run 04:33:29 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 327389184; Read time: 0; Write time: 336496 04:33:29 INFO - TinderboxPrint: CPU usage
13.0% 04:33:29 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 04:33:29 INFO - TinderboxPrint: I/O write bytes / time
327,389,184 / 336,496 04:33:29 INFO - TinderboxPrint: CPU idle
8,272.9 (87.0%) 04:33:29 INFO - TinderboxPrint: CPU user
1,184.7 (12.5%) 04:33:29 INFO - TinderboxPrint: Swap in / out
0 / 0 04:33:29 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 79228928; Read time: 0; Write time: 76240 04:33:29 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 247955456; Read time: 0; Write time: 260256 04:33:29 INFO - Running post-run listener: _upload_blobber_files 04:33:29 INFO - Blob upload gear active. 04:33:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:33:29 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:29 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:29 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:30 INFO - (blobuploader) - INFO - Open directory for files ... 04:33:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:33:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:33:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:37 INFO - (blobuploader) - INFO - Done attempting. 04:33:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:33:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:33:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:38 INFO - (blobuploader) - INFO - Done attempting. 04:33:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:33:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:33:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:33:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:33:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:33:39 INFO - (blobuploader) - INFO - Done attempting. 04:33:39 INFO - (blobuploader) - INFO - Iteration through files over. 04:33:39 INFO - Return code: 0 04:33:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:33:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:33:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f738d6f30b3c693a455442f5a2fc03397281e7ff7653d44a7617b4035c5dd006e25e7c2246d9a0ecbce838f4785387d1e179493eee58f16f153594aa6f7e1682", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a260ead1e8f9c0c689d159810f470c2bdb678c4c346b4c19882d20a1d21aad8bb23ccdc79579d84dae297a08b24cc2495519bcec0ee4644061eb5c12275fb8f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8ec046ab481361707d45a12867b56bd310aa7b7a1369df2b2af6c3b8fcf8110c70ae939dd844a59002a8af5a66a2faa2f2bfa11edbbef5777cd91f1ecfa919e"} 04:33:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:33:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:33:39 INFO - Contents: 04:33:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f738d6f30b3c693a455442f5a2fc03397281e7ff7653d44a7617b4035c5dd006e25e7c2246d9a0ecbce838f4785387d1e179493eee58f16f153594aa6f7e1682", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a260ead1e8f9c0c689d159810f470c2bdb678c4c346b4c19882d20a1d21aad8bb23ccdc79579d84dae297a08b24cc2495519bcec0ee4644061eb5c12275fb8f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8ec046ab481361707d45a12867b56bd310aa7b7a1369df2b2af6c3b8fcf8110c70ae939dd844a59002a8af5a66a2faa2f2bfa11edbbef5777cd91f1ecfa919e"} 04:33:39 INFO - Running post-run listener: copy_logs_to_upload_dir 04:33:39 INFO - Copying logs to upload dir... 04:33:39 INFO - mkdir: /builds/slave/test/build/upload/logs 04:33:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1688.578536 ========= master_lag: 3.95 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 12 secs) (at 2016-08-18 04:33:42.335747) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-18 04:33:42.338056) ========= 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=fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372 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/f738d6f30b3c693a455442f5a2fc03397281e7ff7653d44a7617b4035c5dd006e25e7c2246d9a0ecbce838f4785387d1e179493eee58f16f153594aa6f7e1682", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a260ead1e8f9c0c689d159810f470c2bdb678c4c346b4c19882d20a1d21aad8bb23ccdc79579d84dae297a08b24cc2495519bcec0ee4644061eb5c12275fb8f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8ec046ab481361707d45a12867b56bd310aa7b7a1369df2b2af6c3b8fcf8110c70ae939dd844a59002a8af5a66a2faa2f2bfa11edbbef5777cd91f1ecfa919e"} 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.011172 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/f738d6f30b3c693a455442f5a2fc03397281e7ff7653d44a7617b4035c5dd006e25e7c2246d9a0ecbce838f4785387d1e179493eee58f16f153594aa6f7e1682", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a260ead1e8f9c0c689d159810f470c2bdb678c4c346b4c19882d20a1d21aad8bb23ccdc79579d84dae297a08b24cc2495519bcec0ee4644061eb5c12275fb8f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8ec046ab481361707d45a12867b56bd310aa7b7a1369df2b2af6c3b8fcf8110c70ae939dd844a59002a8af5a66a2faa2f2bfa11edbbef5777cd91f1ecfa919e"}' ========= master_lag: 1.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-08-18 04:33:43.599129) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:33:43.599505) ========= 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=fa44defc139c4ba8574fe040000001db-1471517490.604323-1635235372 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.67 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:33:44.277642) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:33:44.278025) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:33:44.298780) ========= ========= Total master_lag: 5.98 =========