builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-007 starttime: 1472737945.92 results: success (0) buildid: 20160830104522 builduid: 5655a65e53c8465d859cdd3cc9117707 revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.920369) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.920962) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.921234) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.114194) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.114503) ========= 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=923d835db2ba3686ebe126b700000192-1472734596.800451-993218060 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 06:52:27-- 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% 88.7M=0s 2016-09-01 06:52:27 (88.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138060 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.345619) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.345960) ========= 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=923d835db2ba3686ebe126b700000192-1472734596.800451-993218060 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019518 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.469531) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.472931) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bac8c738419eb9ff1fe0947120e879707d19c97b --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 bac8c738419eb9ff1fe0947120e879707d19c97b --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=923d835db2ba3686ebe126b700000192-1472734596.800451-993218060 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 06:52:27,413 truncating revision to first 12 chars 2016-09-01 06:52:27,413 Setting DEBUG logging. 2016-09-01 06:52:27,413 attempt 1/10 2016-09-01 06:52:27,413 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bac8c738419e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 06:52:27,681 unpacking tar archive at: mozilla-aurora-bac8c738419e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.667135 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.176820) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.177083) ========= script_repo_revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.177426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.177734) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.186677) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-01 06:52:27.186939) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=923d835db2ba3686ebe126b700000192-1472734596.800451-993218060 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:52:28 INFO - MultiFileLogger online at 20160901 06:52:28 in /builds/slave/test 06:52:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:52:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:52:28 INFO - {'append_to_log': False, 06:52:28 INFO - 'base_work_dir': '/builds/slave/test', 06:52:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:52:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:52:28 INFO - 'branch': 'Mozilla-Aurora', 06:52:28 INFO - 'buildbot_json_path': 'buildprops.json', 06:52:28 INFO - 'config_files': ('talos/linux_config.py',), 06:52:28 INFO - 'default_actions': ('clobber', 06:52:28 INFO - 'read-buildbot-config', 06:52:28 INFO - 'download-and-extract', 06:52:28 INFO - 'populate-webroot', 06:52:28 INFO - 'create-virtualenv', 06:52:28 INFO - 'install', 06:52:28 INFO - 'run-tests'), 06:52:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:52:28 INFO - 'download_minidump_stackwalk': True, 06:52:28 INFO - 'download_symbols': 'ondemand', 06:52:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:52:28 INFO - 'tooltool.py': '/tools/tooltool.py', 06:52:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:52:28 INFO - '/tools/misc-python/virtualenv.py')}, 06:52:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:52:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:52:28 INFO - 'installer_path': 'installer.exe', 06:52:28 INFO - 'log_level': 'info', 06:52:28 INFO - 'log_name': 'talos', 06:52:28 INFO - 'log_to_console': True, 06:52:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:52:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:52:28 INFO - 'opt_config_files': (), 06:52:28 INFO - 'pip_index': False, 06:52:28 INFO - 'sps_profile': False, 06:52:28 INFO - 'sps_profile_interval': 0, 06:52:28 INFO - 'suite': 'dromaeojs-e10s', 06:52:28 INFO - 'system_bits': '32', 06:52:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:52:28 INFO - 'title': 'talos-linux64-ix-007', 06:52:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:52:28 INFO - 'use_talos_json': True, 06:52:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:52:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:52:28 INFO - 'work_dir': 'build'} 06:52:28 INFO - [mozharness: 2016-09-01 13:52:28.197674Z] Running clobber step. 06:52:28 INFO - Running pre-action listener: _resource_record_pre_action 06:52:28 INFO - Running main action method: clobber 06:52:28 INFO - rmtree: /builds/slave/test/build 06:52:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:52:29 INFO - Running post-action listener: _resource_record_post_action 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.181868Z] Finished clobber step (success) 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.181968Z] Running read-buildbot-config step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: read_buildbot_config 06:52:29 INFO - Using buildbot properties: 06:52:29 INFO - { 06:52:29 INFO - "project": "", 06:52:29 INFO - "product": "firefox", 06:52:29 INFO - "script_repo_revision": "production", 06:52:29 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 06:52:29 INFO - "repository": "", 06:52:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 06:52:29 INFO - "buildid": "20160830104522", 06:52:29 INFO - "pgo_build": "True", 06:52:29 INFO - "basedir": "/builds/slave/test", 06:52:29 INFO - "buildnumber": 519, 06:52:29 INFO - "slavename": "talos-linux64-ix-007", 06:52:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:52:29 INFO - "platform": "ubuntu64_hw", 06:52:29 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:52:29 INFO - "branch": "mozilla-aurora", 06:52:29 INFO - "repo_path": "releases/mozilla-aurora", 06:52:29 INFO - "revision": "bac8c738419eb9ff1fe0947120e879707d19c97b", 06:52:29 INFO - "stage_platform": "linux64-pgo", 06:52:29 INFO - "builduid": "5655a65e53c8465d859cdd3cc9117707", 06:52:29 INFO - "slavebuilddir": "test" 06:52:29 INFO - } 06:52:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 06:52:29 INFO - Running post-action listener: _resource_record_post_action 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.184400Z] Finished read-buildbot-config step (success) 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.184490Z] Running download-and-extract step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: download_and_extract 06:52:29 INFO - mkdir: /builds/slave/test/build/tests 06:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:52:29 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:52:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:52:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:52:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:52:29 INFO - retry: Failed, sleeping 30 seconds before retrying 06:52:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:52:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:52:59 INFO - retry: Failed, sleeping 60 seconds before retrying 06:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:59 INFO - Downloaded 1361 bytes. 06:53:59 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:59 INFO - Using the following test package requirements: 06:53:59 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:59 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:53:59 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:53:59 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'jsshell-linux-x86_64.zip'], 06:53:59 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:53:59 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:59 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:53:59 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:53:59 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:53:59 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:59 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:53:59 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:53:59 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')]} 06:53:59 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:53:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:53:59 INFO - retry: Failed, sleeping 30 seconds before retrying 06:54:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:54:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:54:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:54:29 INFO - retry: Failed, sleeping 60 seconds before retrying 06:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - Caught exception: HTTP Error 404: Not Found 06:55:29 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:30 INFO - Downloaded 21769513 bytes. 06:55:30 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'] 06:55:30 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 06:55:32 INFO - Return code: 0 06:55:32 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 06:55:32 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')]} 06:55:32 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:55:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:55:32 INFO - retry: Failed, sleeping 30 seconds before retrying 06:56:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:56:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:56:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:56:02 INFO - retry: Failed, sleeping 60 seconds before retrying 06:57:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:57:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:57:02 INFO - Caught exception: HTTP Error 404: Not Found 06:57:02 INFO - Caught exception: HTTP Error 404: Not Found 06:57:02 INFO - Caught exception: HTTP Error 404: Not Found 06:57:02 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:57:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:03 INFO - Downloaded 21769513 bytes. 06:57:03 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'] 06:57:03 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 06:57:04 INFO - Return code: 0 06:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:04 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:57:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:57:04 INFO - retry: Failed, sleeping 30 seconds before retrying 06:57:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:57:34 INFO - retry: Failed, sleeping 60 seconds before retrying 06:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:58:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:58:34 INFO - Caught exception: HTTP Error 404: Not Found 06:58:34 INFO - Caught exception: HTTP Error 404: Not Found 06:58:34 INFO - Caught exception: HTTP Error 404: Not Found 06:58:34 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:35 INFO - Downloaded 14413723 bytes. 06:58:35 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'] 06:58:35 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 06:58:35 INFO - Return code: 0 06:58:35 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')]} 06:58:35 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:58:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:58:35 INFO - retry: Failed, sleeping 30 seconds before retrying 06:59:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:59:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:59:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:59:05 INFO - retry: Failed, sleeping 60 seconds before retrying 07:00:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 07:00:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:00:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 07:00:05 INFO - Caught exception: HTTP Error 404: Not Found 07:00:05 INFO - Caught exception: HTTP Error 404: Not Found 07:00:05 INFO - Caught exception: HTTP Error 404: Not Found 07:00:05 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 07:00:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 07:00:06 INFO - Downloaded 63889067 bytes. 07:00:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:06 INFO - mkdir: /builds/slave/test/properties 07:00:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:06 INFO - Contents: 07:00:06 INFO - build_url:https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:07 INFO - Running post-action listener: _resource_record_post_action 07:00:07 INFO - Running post-action listener: set_extra_try_arguments 07:00:07 INFO - [mozharness: 2016-09-01 14:00:07.586606Z] Finished download-and-extract step (success) 07:00:07 INFO - [mozharness: 2016-09-01 14:00:07.586837Z] Running populate-webroot step. 07:00:07 INFO - Running pre-action listener: _resource_record_pre_action 07:00:07 INFO - Running main action method: populate_webroot 07:00:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:07 INFO - '--tppagecycles', 07:00:07 INFO - '7'], 07:00:07 INFO - 'tests': ['tsvgm']}}, 07:00:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['damp', 'tps']}, 07:00:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'tests': ['damp', 'tps']}, 07:00:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['dromaeo_dom']}, 07:00:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['basic_compositor_video']}, 07:00:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:00:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['a11yr', 07:00:07 INFO - 'ts_paint', 07:00:07 INFO - 'tpaint', 07:00:07 INFO - 'sessionrestore', 07:00:07 INFO - 'sessionrestore_no_auto_restore', 07:00:07 INFO - 'tabpaint']}, 07:00:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['tsvgx', 07:00:07 INFO - 'tsvgr_opacity', 07:00:07 INFO - 'tart', 07:00:07 INFO - 'tscrollx', 07:00:07 INFO - 'cart']}, 07:00:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:07 INFO - 'tsvgr_opacity', 07:00:07 INFO - 'tart', 07:00:07 INFO - 'tscrollx', 07:00:07 INFO - 'cart']}, 07:00:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'talos_options': ['--disable-e10s'], 07:00:07 INFO - 'tests': ['tp5o']}, 07:00:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'tests': ['tp5o']}, 07:00:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'talos_options': ['--disable-e10s', 07:00:07 INFO - '--xperf_path', 07:00:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:07 INFO - 'tests': ['tp5n']}, 07:00:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:07 INFO - 'talos_options': ['--xperf_path', 07:00:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:07 INFO - 'tests': ['tp5n']}}, 07:00:07 INFO - 'talos.zip': {'path': '', 07:00:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:07 INFO - Running post-action listener: _resource_record_post_action 07:00:07 INFO - [mozharness: 2016-09-01 14:00:07.609136Z] Finished populate-webroot step (success) 07:00:07 INFO - [mozharness: 2016-09-01 14:00:07.609225Z] Running create-virtualenv step. 07:00:07 INFO - Running pre-action listener: _resource_record_pre_action 07:00:07 INFO - Running main action method: create_virtualenv 07:00:07 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:07 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 07:00:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:07 INFO - Using real prefix '/usr' 07:00:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:00:08 INFO - Installing distribute.............................................................................................................................................................................................done. 07:00:10 INFO - Installing pip.................done. 07:00:10 INFO - Return code: 0 07:00:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:00:10 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')]} 07:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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')]} 07:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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 0x1fab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21da400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, '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': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', '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 07:00:10 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 07:00:10 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 07:00:10 INFO - Using env: {'DISPLAY': ':0', 07:00:10 INFO - 'HOME': '/home/cltbld', 07:00:10 INFO - 'LANG': 'en_US.UTF-8', 07:00:10 INFO - 'LANGUAGE': 'en_US:en', 07:00:10 INFO - 'LOGNAME': 'cltbld', 07:00:10 INFO - 'MAIL': '/var/mail/cltbld', 07:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:10 INFO - 'MOZ_NO_REMOTE': '1', 07:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:10 INFO - 'NO_EM_RESTART': '1', 07:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:10 INFO - 'PWD': '/builds/slave/test', 07:00:10 INFO - 'SHELL': '/bin/bash', 07:00:10 INFO - 'SHLVL': '1', 07:00:10 INFO - 'TERM': 'linux', 07:00:10 INFO - 'TMOUT': '86400', 07:00:10 INFO - 'USER': 'cltbld', 07:00:10 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:10 INFO - '_': '/tools/buildbot/bin/python'} 07:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:10 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 07:00:10 INFO - Cleaning up... 07:00:10 INFO - Return code: 0 07:00:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:00:10 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')]} 07:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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')]} 07:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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 0x1fab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21da400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, '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': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', '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 07:00:10 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 07:00:10 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 07:00:10 INFO - Using env: {'DISPLAY': ':0', 07:00:10 INFO - 'HOME': '/home/cltbld', 07:00:10 INFO - 'LANG': 'en_US.UTF-8', 07:00:10 INFO - 'LANGUAGE': 'en_US:en', 07:00:10 INFO - 'LOGNAME': 'cltbld', 07:00:10 INFO - 'MAIL': '/var/mail/cltbld', 07:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:10 INFO - 'MOZ_NO_REMOTE': '1', 07:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:10 INFO - 'NO_EM_RESTART': '1', 07:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:10 INFO - 'PWD': '/builds/slave/test', 07:00:10 INFO - 'SHELL': '/bin/bash', 07:00:10 INFO - 'SHLVL': '1', 07:00:10 INFO - 'TERM': 'linux', 07:00:10 INFO - 'TMOUT': '86400', 07:00:10 INFO - 'USER': 'cltbld', 07:00:10 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:10 INFO - '_': '/tools/buildbot/bin/python'} 07:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:10 INFO - Downloading/unpacking psutil>=3.1.1 07:00:10 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 07:00:10 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 07:00:10 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 07:00:10 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 07:00:10 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 07:00:10 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 07:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:12 INFO - Installing collected packages: psutil 07:00:12 INFO - Running setup.py install for psutil 07:00:12 INFO - building 'psutil._psutil_linux' extension 07:00:12 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 07:00:12 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 07:00:12 INFO - building 'psutil._psutil_posix' extension 07:00:12 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 07:00:12 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 07:00:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:13 INFO - Successfully installed psutil 07:00:13 INFO - Cleaning up... 07:00:13 INFO - Return code: 0 07:00:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21da400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, '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': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', '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 07:00:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:00:13 INFO - Using env: {'DISPLAY': ':0', 07:00:13 INFO - 'HOME': '/home/cltbld', 07:00:13 INFO - 'LANG': 'en_US.UTF-8', 07:00:13 INFO - 'LANGUAGE': 'en_US:en', 07:00:13 INFO - 'LOGNAME': 'cltbld', 07:00:13 INFO - 'MAIL': '/var/mail/cltbld', 07:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:13 INFO - 'MOZ_NO_REMOTE': '1', 07:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:13 INFO - 'NO_EM_RESTART': '1', 07:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:13 INFO - 'PWD': '/builds/slave/test', 07:00:13 INFO - 'SHELL': '/bin/bash', 07:00:13 INFO - 'SHLVL': '1', 07:00:13 INFO - 'TERM': 'linux', 07:00:13 INFO - 'TMOUT': '86400', 07:00:13 INFO - 'USER': 'cltbld', 07:00:13 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:13 INFO - '_': '/tools/buildbot/bin/python'} 07:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:00:13 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 07:00:13 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 07:00:13 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 07:00:13 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 07:00:13 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 07:00:13 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 07:00:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:00:15 INFO - Installing collected packages: mozsystemmonitor 07:00:15 INFO - Running setup.py install for mozsystemmonitor 07:00:15 INFO - Successfully installed mozsystemmonitor 07:00:15 INFO - Cleaning up... 07:00:15 INFO - Return code: 0 07:00:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:00:15 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')]} 07:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:15 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')]} 07:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:15 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 0x1fab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21da400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, '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': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', '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 07:00:15 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 07:00:15 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 07:00:15 INFO - Using env: {'DISPLAY': ':0', 07:00:15 INFO - 'HOME': '/home/cltbld', 07:00:15 INFO - 'LANG': 'en_US.UTF-8', 07:00:15 INFO - 'LANGUAGE': 'en_US:en', 07:00:15 INFO - 'LOGNAME': 'cltbld', 07:00:15 INFO - 'MAIL': '/var/mail/cltbld', 07:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:15 INFO - 'MOZ_NO_REMOTE': '1', 07:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:15 INFO - 'NO_EM_RESTART': '1', 07:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:15 INFO - 'PWD': '/builds/slave/test', 07:00:15 INFO - 'SHELL': '/bin/bash', 07:00:15 INFO - 'SHLVL': '1', 07:00:15 INFO - 'TERM': 'linux', 07:00:15 INFO - 'TMOUT': '86400', 07:00:15 INFO - 'USER': 'cltbld', 07:00:15 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:15 INFO - '_': '/tools/buildbot/bin/python'} 07:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:15 INFO - Downloading/unpacking jsonschema==2.5.1 07:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:00:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:17 INFO - Downloading functools32-3.2.3-2.tar.gz 07:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:00:17 INFO - warning: no files found matching '*.txt' 07:00:17 INFO - no previously-included directories found matching 'build' 07:00:17 INFO - no previously-included directories found matching 'dist' 07:00:17 INFO - no previously-included directories found matching '.git*' 07:00:17 INFO - Installing collected packages: jsonschema, functools32 07:00:17 INFO - Running setup.py install for functools32 07:00:17 INFO - warning: no files found matching '*.txt' 07:00:17 INFO - no previously-included directories found matching 'build' 07:00:17 INFO - no previously-included directories found matching 'dist' 07:00:17 INFO - no previously-included directories found matching '.git*' 07:00:17 INFO - Successfully installed jsonschema functools32 07:00:17 INFO - Cleaning up... 07:00:18 INFO - Return code: 0 07:00:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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', '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 0x1fab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21da400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, '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': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', '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 07:00: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:00: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 functools32==3.2.3-2 07:00:18 INFO - Using env: {'DISPLAY': ':0', 07:00:18 INFO - 'HOME': '/home/cltbld', 07:00:18 INFO - 'LANG': 'en_US.UTF-8', 07:00:18 INFO - 'LANGUAGE': 'en_US:en', 07:00:18 INFO - 'LOGNAME': 'cltbld', 07:00:18 INFO - 'MAIL': '/var/mail/cltbld', 07:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:18 INFO - 'MOZ_NO_REMOTE': '1', 07:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:18 INFO - 'NO_EM_RESTART': '1', 07:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:18 INFO - 'PWD': '/builds/slave/test', 07:00:18 INFO - 'SHELL': '/bin/bash', 07:00:18 INFO - 'SHLVL': '1', 07:00:18 INFO - 'TERM': 'linux', 07:00:18 INFO - 'TMOUT': '86400', 07:00:18 INFO - 'USER': 'cltbld', 07:00:18 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:18 INFO - '_': '/tools/buildbot/bin/python'} 07:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:00:18 INFO - Cleaning up... 07:00:18 INFO - Return code: 0 07:00:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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', '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 0x1fab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21da400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, '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': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', '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 07:00: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:00: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 blobuploader==1.2.4 07:00:18 INFO - Using env: {'DISPLAY': ':0', 07:00:18 INFO - 'HOME': '/home/cltbld', 07:00:18 INFO - 'LANG': 'en_US.UTF-8', 07:00:18 INFO - 'LANGUAGE': 'en_US:en', 07:00:18 INFO - 'LOGNAME': 'cltbld', 07:00:18 INFO - 'MAIL': '/var/mail/cltbld', 07:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:18 INFO - 'MOZ_NO_REMOTE': '1', 07:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:18 INFO - 'NO_EM_RESTART': '1', 07:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:18 INFO - 'PWD': '/builds/slave/test', 07:00:18 INFO - 'SHELL': '/bin/bash', 07:00:18 INFO - 'SHLVL': '1', 07:00:18 INFO - 'TERM': 'linux', 07:00:18 INFO - 'TMOUT': '86400', 07:00:18 INFO - 'USER': 'cltbld', 07:00:18 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:18 INFO - '_': '/tools/buildbot/bin/python'} 07:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:18 INFO - Downloading/unpacking blobuploader==1.2.4 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00:20 INFO - Downloading blobuploader-1.2.4.tar.gz 07:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:00:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:00:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:20 INFO - Downloading docopt-0.6.1.tar.gz 07:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:00:21 INFO - Installing collected packages: blobuploader, requests, docopt 07:00:21 INFO - Running setup.py install for blobuploader 07:00:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:00:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:00:21 INFO - Running setup.py install for requests 07:00:21 INFO - Running setup.py install for docopt 07:00:21 INFO - Successfully installed blobuploader requests docopt 07:00:21 INFO - Cleaning up... 07:00:21 INFO - Return code: 0 07:00:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:21 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 0x1fab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21da400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, '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': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', '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 07:00:21 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 07:00:21 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 07:00:21 INFO - Using env: {'DISPLAY': ':0', 07:00:21 INFO - 'HOME': '/home/cltbld', 07:00:21 INFO - 'LANG': 'en_US.UTF-8', 07:00:21 INFO - 'LANGUAGE': 'en_US:en', 07:00:21 INFO - 'LOGNAME': 'cltbld', 07:00:21 INFO - 'MAIL': '/var/mail/cltbld', 07:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:21 INFO - 'MOZ_NO_REMOTE': '1', 07:00:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:21 INFO - 'NO_EM_RESTART': '1', 07:00:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:21 INFO - 'PWD': '/builds/slave/test', 07:00:21 INFO - 'SHELL': '/bin/bash', 07:00:21 INFO - 'SHLVL': '1', 07:00:21 INFO - 'TERM': 'linux', 07:00:21 INFO - 'TMOUT': '86400', 07:00:21 INFO - 'USER': 'cltbld', 07:00:21 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:21 INFO - '_': '/tools/buildbot/bin/python'} 07:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:21 INFO - Running setup.py (path:/tmp/pip-lr2rsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:21 INFO - Running setup.py (path:/tmp/pip-PlyqAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:22 INFO - Running setup.py (path:/tmp/pip-wRRNk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:22 INFO - Running setup.py (path:/tmp/pip-tRYkoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:22 INFO - Running setup.py (path:/tmp/pip-rzlFrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:22 INFO - Running setup.py (path:/tmp/pip-210eeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:22 INFO - Running setup.py (path:/tmp/pip-mFzl4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:22 INFO - Running setup.py (path:/tmp/pip-BFdsFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:22 INFO - Running setup.py (path:/tmp/pip-fyf7L6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:22 INFO - Running setup.py (path:/tmp/pip-I7PFsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:22 INFO - Running setup.py (path:/tmp/pip-4tXw_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:22 INFO - Running setup.py (path:/tmp/pip-QVbFbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:22 INFO - Running setup.py (path:/tmp/pip-eoGJwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:22 INFO - Running setup.py (path:/tmp/pip-cgz_lz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:22 INFO - Running setup.py (path:/tmp/pip-Oyoq6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:22 INFO - Running setup.py (path:/tmp/pip-MyPoeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:23 INFO - Running setup.py (path:/tmp/pip-Jqohg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:00:23 INFO - Running setup.py install for manifestparser 07:00:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozcrash 07:00:23 INFO - Running setup.py install for mozdebug 07:00:23 INFO - Running setup.py install for mozdevice 07:00:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozfile 07:00:23 INFO - Running setup.py install for mozhttpd 07:00:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozinfo 07:00:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozInstall 07:00:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozleak 07:00:24 INFO - Running setup.py install for mozlog 07:00:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for moznetwork 07:00:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozprocess 07:00:24 INFO - Running setup.py install for mozprofile 07:00:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozrunner 07:00:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozscreenshot 07:00:24 INFO - Running setup.py install for moztest 07:00:24 INFO - Running setup.py install for mozversion 07:00:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:00:24 INFO - Cleaning up... 07:00:24 INFO - Return code: 0 07:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:24 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 0x1fab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21da400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, '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': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', '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 07:00:24 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 07:00:24 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 07:00:24 INFO - Using env: {'DISPLAY': ':0', 07:00:24 INFO - 'HOME': '/home/cltbld', 07:00:24 INFO - 'LANG': 'en_US.UTF-8', 07:00:24 INFO - 'LANGUAGE': 'en_US:en', 07:00:24 INFO - 'LOGNAME': 'cltbld', 07:00:24 INFO - 'MAIL': '/var/mail/cltbld', 07:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:24 INFO - 'MOZ_NO_REMOTE': '1', 07:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:24 INFO - 'NO_EM_RESTART': '1', 07:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:24 INFO - 'PWD': '/builds/slave/test', 07:00:24 INFO - 'SHELL': '/bin/bash', 07:00:24 INFO - 'SHLVL': '1', 07:00:24 INFO - 'TERM': 'linux', 07:00:24 INFO - 'TMOUT': '86400', 07:00:24 INFO - 'USER': 'cltbld', 07:00:24 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:24 INFO - '_': '/tools/buildbot/bin/python'} 07:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:25 INFO - Running setup.py (path:/tmp/pip-kf2N4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:25 INFO - Running setup.py (path:/tmp/pip-Zj5NAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:25 INFO - Running setup.py (path:/tmp/pip-E9FFj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:25 INFO - Running setup.py (path:/tmp/pip-PAdJPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:25 INFO - Running setup.py (path:/tmp/pip-4p7FKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:25 INFO - Running setup.py (path:/tmp/pip-IaKNMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:25 INFO - Running setup.py (path:/tmp/pip-Sw48pZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:25 INFO - Running setup.py (path:/tmp/pip-TK01zW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:25 INFO - Running setup.py (path:/tmp/pip-GNvWTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:25 INFO - Running setup.py (path:/tmp/pip-DWTKX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:25 INFO - Running setup.py (path:/tmp/pip-LLPtFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:25 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)) 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:25 INFO - Running setup.py (path:/tmp/pip-GsojyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:26 INFO - Running setup.py (path:/tmp/pip-Ff9uQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:26 INFO - Running setup.py (path:/tmp/pip-nKr95v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:26 INFO - Running setup.py (path:/tmp/pip-2X7tfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:26 INFO - Running setup.py (path:/tmp/pip-NDUo29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:26 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)) 07:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:26 INFO - Running setup.py (path:/tmp/pip-8_bMKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:26 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)) 07:00:26 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)) 07:00:26 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)) 07:00:26 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)) 07:00:26 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)) 07:00:26 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)) 07:00:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00:28 INFO - Downloading blessings-1.6.tar.gz 07:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:00:28 INFO - Installing collected packages: blessings 07:00:28 INFO - Running setup.py install for blessings 07:00:28 INFO - Successfully installed blessings 07:00:28 INFO - Cleaning up... 07:00:28 INFO - Return code: 0 07:00:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:00:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:28 INFO - Reading from file tmpfile_stdout 07:00:28 INFO - Current package versions: 07:00:28 INFO - argparse == 1.2.1 07:00:28 INFO - blessings == 1.6 07:00:28 INFO - blobuploader == 1.2.4 07:00:28 INFO - docopt == 0.6.1 07:00:28 INFO - functools32 == 3.2.3-2 07:00:28 INFO - jsonschema == 2.5.1 07:00:28 INFO - manifestparser == 1.1 07:00:28 INFO - mozInstall == 1.12 07:00:28 INFO - mozcrash == 0.17 07:00:28 INFO - mozdebug == 0.1 07:00:28 INFO - mozdevice == 0.48 07:00:28 INFO - mozfile == 1.2 07:00:28 INFO - mozhttpd == 0.7 07:00:28 INFO - mozinfo == 0.9 07:00:28 INFO - mozleak == 0.1 07:00:28 INFO - mozlog == 3.3 07:00:28 INFO - moznetwork == 0.27 07:00:28 INFO - mozprocess == 0.23 07:00:28 INFO - mozprofile == 0.28 07:00:28 INFO - mozrunner == 6.12 07:00:28 INFO - mozscreenshot == 0.1 07:00:28 INFO - mozsystemmonitor == 0.3 07:00:28 INFO - moztest == 0.7 07:00:28 INFO - mozversion == 1.4 07:00:28 INFO - psutil == 3.1.1 07:00:28 INFO - requests == 1.2.3 07:00:28 INFO - wsgiref == 0.1.2 07:00:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:28 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')]} 07:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:28 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')]} 07:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:28 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 0x1fab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21da400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, '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': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', '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 07:00:28 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 07:00:28 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 07:00:28 INFO - Using env: {'DISPLAY': ':0', 07:00:28 INFO - 'HOME': '/home/cltbld', 07:00:28 INFO - 'LANG': 'en_US.UTF-8', 07:00:28 INFO - 'LANGUAGE': 'en_US:en', 07:00:28 INFO - 'LOGNAME': 'cltbld', 07:00:28 INFO - 'MAIL': '/var/mail/cltbld', 07:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:28 INFO - 'MOZ_NO_REMOTE': '1', 07:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:28 INFO - 'NO_EM_RESTART': '1', 07:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:28 INFO - 'PWD': '/builds/slave/test', 07:00:28 INFO - 'SHELL': '/bin/bash', 07:00:28 INFO - 'SHLVL': '1', 07:00:28 INFO - 'TERM': 'linux', 07:00:28 INFO - 'TMOUT': '86400', 07:00:28 INFO - 'USER': 'cltbld', 07:00:28 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:28 INFO - '_': '/tools/buildbot/bin/python'} 07:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 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)) 07:00:28 INFO - Cleaning up... 07:00:28 INFO - Return code: 0 07:00:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:00:28 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')]} 07:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:28 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')]} 07:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:28 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 0x1fab030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21da400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, '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': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', '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 07:00:28 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 07:00:28 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 07:00:28 INFO - Using env: {'DISPLAY': ':0', 07:00:28 INFO - 'HOME': '/home/cltbld', 07:00:28 INFO - 'LANG': 'en_US.UTF-8', 07:00:28 INFO - 'LANGUAGE': 'en_US:en', 07:00:28 INFO - 'LOGNAME': 'cltbld', 07:00:28 INFO - 'MAIL': '/var/mail/cltbld', 07:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:28 INFO - 'MOZ_NO_REMOTE': '1', 07:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:28 INFO - 'NO_EM_RESTART': '1', 07:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:28 INFO - 'PWD': '/builds/slave/test', 07:00:28 INFO - 'SHELL': '/bin/bash', 07:00:28 INFO - 'SHLVL': '1', 07:00:28 INFO - 'TERM': 'linux', 07:00:28 INFO - 'TMOUT': '86400', 07:00:28 INFO - 'USER': 'cltbld', 07:00:28 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:28 INFO - '_': '/tools/buildbot/bin/python'} 07:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:00:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Running post-action listener: _resource_record_post_action 07:00:29 INFO - Running post-action listener: _start_resource_monitoring 07:00:29 INFO - Starting resource monitoring. 07:00:29 INFO - [mozharness: 2016-09-01 14:00:29.207354Z] Finished create-virtualenv step (success) 07:00:29 INFO - [mozharness: 2016-09-01 14:00:29.207818Z] Running install step. 07:00:29 INFO - Running pre-action listener: _resource_record_pre_action 07:00:29 INFO - Running main action method: install 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Detecting whether we're running mozinstall >=1.0... 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Output received: 07:00:29 INFO - Usage: mozinstall [options] installer 07:00:29 INFO - Options: 07:00:29 INFO - -h, --help show this help message and exit 07:00:29 INFO - -d DEST, --destination=DEST 07:00:29 INFO - Directory to install application into. [default: 07:00:29 INFO - "/builds/slave/test"] 07:00:29 INFO - --app=APP Application being installed. [default: firefox] 07:00:29 INFO - mkdir: /builds/slave/test/build/application 07:00:29 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'] 07:00:29 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 07:00:46 INFO - Reading from file tmpfile_stdout 07:00:46 INFO - Output received: 07:00:46 INFO - /builds/slave/test/build/application/firefox/firefox 07:00:46 INFO - Running post-action listener: _resource_record_post_action 07:00:46 INFO - [mozharness: 2016-09-01 14:00:46.179587Z] Finished install step (success) 07:00:46 INFO - [mozharness: 2016-09-01 14:00:46.179866Z] Running run-tests step. 07:00:46 INFO - Running pre-action listener: _resource_record_pre_action 07:00:46 INFO - Running main action method: run_tests 07:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:00:46 INFO - Python 2.7.3 07:00:46 INFO - Return code: 0 07:00:46 INFO - grabbing minidump binary from tooltool 07:00:46 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')]} 07:00:46 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 0x2269570>, '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 0x226a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x226a900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:00:46 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 07:00:46 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 07:00:46 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 07:00:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:00:46 INFO - Return code: 0 07:00:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:00:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:00:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:00:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:00:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:00:46 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:00:46 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:00:46 INFO - Using env: {'DISPLAY': ':0', 07:00:46 INFO - 'HOME': '/home/cltbld', 07:00:46 INFO - 'LANG': 'en_US.UTF-8', 07:00:46 INFO - 'LANGUAGE': 'en_US:en', 07:00:46 INFO - 'LOGNAME': 'cltbld', 07:00:46 INFO - 'MAIL': '/var/mail/cltbld', 07:00:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:00:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:46 INFO - 'MOZ_NO_REMOTE': '1', 07:00:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:46 INFO - 'NO_EM_RESTART': '1', 07:00:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:46 INFO - 'PWD': '/builds/slave/test', 07:00:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:00:46 INFO - 'SHELL': '/bin/bash', 07:00:46 INFO - 'SHLVL': '1', 07:00:46 INFO - 'TERM': 'linux', 07:00:46 INFO - 'TMOUT': '86400', 07:00:46 INFO - 'USER': 'cltbld', 07:00:46 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1472734596.800451-993218060', 07:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:46 INFO - '_': '/tools/buildbot/bin/python'} 07:00:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:00:46 INFO - mozversion application_buildid: 20160830104522 07:00:46 INFO - mozversion application_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:00:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:00:46 INFO - mozversion application_name: Firefox 07:00:46 INFO - mozversion application_remotingname: firefox-dev 07:00:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:46 INFO - mozversion application_vendor: Mozilla 07:00:46 INFO - mozversion application_version: 50.0a2 07:00:46 INFO - mozversion platform_buildid: 20160830104522 07:00:46 INFO - mozversion platform_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:46 INFO - mozversion platform_version: 50.0a2 07:00:46 INFO - using testdate: 1472738446 07:00:46 INFO - actual date: 1472738446 07:00:46 INFO - starting webserver on 'localhost:57282' 07:00:46 INFO - SUITE-START | Running 2 tests 07:00:46 INFO - TEST-START | dromaeo_css 07:00:46 INFO - operating with platform_type : linux_ 07:00:46 INFO - Initialising browser for dromaeo_css test... 07:00:46 INFO - TEST-INFO | started process 30148 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUCzm5b/profile http://localhost:57282/getInfo.html) 07:00:50 INFO - PROCESS | 30148 | __metrics Screen width/height:1600/1200 07:00:50 INFO - PROCESS | 30148 | colorDepth:24 07:00:50 INFO - PROCESS | 30148 | Browser inner width/height: 1024/697 07:00:50 INFO - PROCESS | 30148 | __metrics 07:00:50 ERROR - PROCESS | 30148 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:00:51 INFO - TEST-INFO | 30148: exit 0 07:00:51 INFO - Browser initialized. 07:00:51 INFO - Running cycle 1/1 for dromaeo_css test... 07:00:51 INFO - TEST-INFO | started process 30273 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUCzm5b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:01:03 INFO - PROCESS | 30273 | RSS: Main: 152743936 07:01:03 INFO - PROCESS | 30273 | 07:01:52 INFO - PROCESS | 30273 | 1472738512966 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] 07:04:01 INFO - PROCESS | 30273 | Cycle 1(1): loaded http://localhost:57282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57282/tests/dromaeo/cssquery-ext.html) 07:04:02 INFO - PROCESS | 30273 | RSS: Main: 158167040 07:04:02 INFO - PROCESS | 30273 | 07:07:04 INFO - PROCESS | 30273 | Cycle 1(1): loaded http://localhost:57282/tests/dromaeo/cssquery-ext.html (next: http://localhost:57282/tests/dromaeo/cssquery-jquery.html) 07:07:05 INFO - PROCESS | 30273 | RSS: Main: 153874432 07:07:05 INFO - PROCESS | 30273 | 07:10:06 INFO - PROCESS | 30273 | Cycle 1(1): loaded http://localhost:57282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57282/tests/dromaeo/cssquery-mootools.html) 07:10:07 INFO - PROCESS | 30273 | RSS: Main: 154259456 07:10:07 INFO - PROCESS | 30273 | 07:13:09 INFO - PROCESS | 30273 | Cycle 1(1): loaded http://localhost:57282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57282/tests/dromaeo/cssquery-prototype.html) 07:13:09 INFO - PROCESS | 30273 | RSS: Main: 152793088 07:13:09 INFO - PROCESS | 30273 | 07:16:12 INFO - PROCESS | 30273 | Cycle 1(1): loaded http://localhost:57282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57282/tests/dromaeo/cssquery-yui.html) 07:16:13 INFO - PROCESS | 30273 | RSS: Main: 155332608 07:16:13 INFO - PROCESS | 30273 | 07:19:15 INFO - PROCESS | 30273 | Cycle 1(1): loaded http://localhost:57282/tests/dromaeo/cssquery-yui.html (next: http://localhost:57282/tests/dromaeo/cssquery-dojo.html) 07:19:15 INFO - PROCESS | 30273 | RSS: Main: 155996160 07:19:15 INFO - PROCESS | 30273 | 07:19:15 INFO - PROCESS | 30273 | __start_tp_report 07:19:15 INFO - PROCESS | 30273 | _x_x_mozilla_page_load 07:19:15 INFO - PROCESS | 30273 | _x_x_mozilla_page_load_details 07:19:15 INFO - PROCESS | 30273 | |i|pagename|runs| 07:19:15 INFO - PROCESS | 30273 | |0;dojo.html;3279.8196099214547;3288.539604455375;3305.6363799981996;3308.586426696663;3322.8172450515226;5758.654480731154;5837.036888913335;5853.209816674747;5868.677222752747;5870.471657550819;5866.560007999396;5917.704114794265;5925.911111333334;5933.762649494015;5935.940640593592;5333.839984800453;5363.061464243761;5391.595630327722;5428.972855135738;5429.4027656957705;5264.39459462161;5282.339707536558;5324.387695415019;5343.251944727741;5387.676739395649;5335.71942733745;5359.517643412092;5364.7317634118335;5376.032314183445;5441.428649991753;5408.945910540903;5427.104527752916;5433.347998240237;5445.918311225335;5468.234447177399;5074.31496747938;5128.051310507531;5151;5314.787408503656;5338.359396872362;5670.943290567083;5726.31284245889;5747.310322761254;5750.051241545182;5782.132680097977;6119.388061193889;6158.1686472326555;6163.137160797447;6170.413810687977;6179;6119.938800611982;6150.384961503813;6194.938050619481;6226.87546249077;6244.188255526798;5900.763969441175;5905.320888097881;5906.350301466835;5914.822555323347;5941.257342832103;6417.2299324081405;6441.645709486023;6449.355064493513;6458.54790164684;6471.967640161769;6004.39956004396;6023.608465449732;6058.242719660043;6111.816645500642;6195.504359651217;6238.719257633417;6245.750169993149;6261.123442718023;6262.906056409158;6263.4362907337645;6211;6220.471259942956;6250.093736408196;6258.186435763413;6262.2172228471445;6025.758969641253;6081.057436097323;6107.816765497042;6229.034499652561;6241.282252540925;4741.786619602126;4745.857624271277;4752.334673145762;4767.61859051275;4769.022350418156;4220.2192594370135;4223.366495025709;4228.4080228768;4248.4052232687445;4249.086446414036;5795.304563452329;5859.677717725481;5883.61756485827;5913.379095195029;5988.850278742979;6201.56589038772;6237.500999919996;6241;6252.155958945576;6291.496680265568;5928.140419639129;5951.672658003851;5964.314103878023;5976.671283079472;5978.8206353809455;5260.736963151904;5336.946630533645;5370.248165256867;5375.059364611177;5375.435579264199;6474.773382931575;6530.967345163243;6542.280349161589;6543.607383557001;6552.50856185788;6300.30696623371;6364.4908407326375;6366.140571023038;6371.936280637135;6374.235091789015;6268.6552239625435;6281.246250449975;6296.496280297473;6495.1556297681;6549.96725016391;6125.540584456273;6136.539759518143;6139.3553676863285;6151.784687535915;6171.197744293213;6204.069389591597;6235.438810507076;6241.719122639402;6246.843828904313;6255.280642725963;5938.317093534299;5956.553258505716;5969.791057312973;6062.696865156813;6089;6038.184845045863;6045.667488288011;6050.2437195350585;6067.908981365372;6088;5999;6009.87980240384;6056.273247210363;6067.332593414632;6069.514438844793;6026.789062382795;6033;6090.9390906090375;6097.2683278006925;6114.510839132948;6010.429009244115;6024.33732289439;6070.332263451105;6073.210482637229;6084.300305464928 07:19:15 INFO - PROCESS | 30273 | |1;ext.html;573.7360814025548;645.4642646603319;646.0212777641871;655.5935320101536;684.7089986755634;41354;45998.310025349565;46017.07965840685;46105.30842037372;46231;45835;46139.30791038137;46183.76908115455;46416.07167856641;46475.07049859001;9068.773280667987;9104.954475227614;9115.544222788867;9213.493257870814;9231.815363692756;9076.8638470423;9187.897452305733;9231.030741772118;9248.722538323827;9260.953695231514;9262.120098590623;9303.953480232589;9319;9320.347575669706;9328.720138395858;9207.815843683122;9231.123043310874;9234.584443700049;9246.722598322061;9279.72160835176;9110.498922559256;9162;9180.127887850644;9185.724428267162;9191.126842949909;43453.565464345265;43594.78202608998;43747;43797.78101109506;43816.561834381566;3785.2051069275485;3809.257194847006;3814.771113733185;3865.2524530250735;3969.5435024972207;34816.12959676003;35092.473612895825;35199.82400087983;35523.11192220189;36877.26245475102;4498.3927169832305;4499.910001799978;4510.909781804346;4515.254982927813;4527.932081018787;379734;383279;383565;384339;385332;37489.81255093707;38244.61755382411;56023.439765602634;56216.43783562194;56608.71695641578;40212.39681404781;40233.79883100566;40260.59739402627;40294;40602.390964135564;17609.647807043788;17613.119344032748;17673.646527069388;17675.29298828058;17795.555111122067;14686.265686715622;14847.925760371128;14872;14899.404023839143;14918.030328028643;9001.909980900238;9138.954305228564;9200.30997675177;9210.585523651453;9288.582013809395;3495.2834668892815;3510.227749895019;3576.40989236773;3583.605803361628;3636.127329440968;1089.8201796703613;1092.415557676639;1094.8631421072366;1179.9469023893946;1205.1804772754435;34941.47587786185;35375.64624353775;35401.46897796535;35425.822870886;35566.822165889;1176.564671071709;1198.3708553009565;1223.9265644061206;1243.9502419903104;1257.1388598809845;18114.094295285184;18118.728219076726;18121.23135512523;18140.365087221882;18207.45377638673;615974;619000;619977;626961;630980;63586.68206658937;64428.35571644317;64520;64671.67664161649;64717.352826471135;1455.44693016653;1491.3289019941287;1499.7975273337959;1505.7214415333199;1506.909585424878;33154.33691326208;33323.500147497325;33592;33735.83132084422;34122.48816267808;55991.16013259886;61086.38913610807;62812.37187628065;62835;68201.65899170472;540435;541111;547824;548482;551179;48757.51242487672;48992;49089.50910490992;49099.75450122727;49420.50579494159;52740;52973;53391.73304133455;53590;53628.463715363905;44445.55554444414;44631.73396485455;46523.534764653275;46562.767186165205;47870.521294788;45068.77465612651;45093.774531127136;45450;45876.58207484979;46067;39081.41377879391;43940.560594393646;44149.779251104825;44592.77703611461;45662;38681.2263754729;39371.409428858016;39767.40348894711;39888.2022359557;40055.39916901191;29883.70116298809;30192.5471117929;30255.243618909702;30379.936702215316;30510.542341865334 07:19:15 INFO - PROCESS | 30273 | |2;jquery.html;415.2338934665541;423.48546515983077;427.87591598436455;434.7391565060964;445.944256967879;19779.604407911833;20998;21505.67741483877;21618;21670;19517.21931122757;20066.197352105897;20953.05711242994;21065.368038958855;21165.365039048815;5364.758585863636;5585.692786896719;5614.606977511576;5623;5632.971835140818;5484.2596249506205;5495.807646732365;5528.55771538276;5533.944660553383;5541.168824676291;5425.104857698475;5541.806036788713;5546.084895992157;5548.500634942854;5568.136938774496;5464.726763661801;5995.460408563206;6037.667928263943;6038.27540695115;6046.758129674829;5211.765470553834;5426.701531415771;5436.619436639437;5452.563794896399;5454.59090568205;14629.561113166623;14892.925535372255;15138.394464221417;15319.693606127927;15345.386184552604;2445.0708730682404;2488.925332240036;2834.3055951292067;3103.7516998640053;3115.3613509230554;10911.399873006982;10939.83590246139;10945.507452164671;10946.945265273702;11335.773284534263;2046.5292982614083;2062.092679221138;2083.593699228651;2091.8117369436823;2093.71734815801;87332.56333718354;87988.56005719994;90041;90440.09559904448;91508;10599.841002384816;10653.307535010199;10856.511456984455;11019.83470247947;11216.775664486664;15176.924115379352;15187;15192.316345764468;15206.847931520764;15208.315625796644;8252.958735206406;8544.914550854412;8618.39671223021;8754.080821513777;8771.38600297973;8704.086070962458;8750.081241469554;8799.164079412445;8921.866172007425;8938.195562399414;4405.669574781904;4610.331501932268;4614.769261536977;4629.421322334708;4631.467381251132;2040.8673436226597;2118.724565806466;2127.1172463427815;2156.773538778437;2175.706279652258;1007.4091718898068;1012.7063151686076;1039.033698660238;1057.376148072641;1107.0977153619774;20450.284240051526;20693;20823.791762082186;20947.37157885266;20980.89509552473;1011.4083261292227;1038.8857225705167;1051.5898799468214;1059.364381371171;1095.7863216672672;10735.194860385502;11408.372539510407;11567.942160289145;11625.24435911663;11668.94165529167;503027;515324;515925;519957;521537;97538;99368;99656;100721;103730;1002.1231422505308;1019.8929112443086;1029.7631544744602;1031.7730099378125;1052.85786418834;17805.46583602494;18511.166997484877;18539.258429662663;18683.626327473103;19007.524811879815;38660.6133938657;45304.32043519416;46959.765201173774;47243.29135062908;48673.75663121662;62891;64020;64341.35658643354;64606;65124.674376627816;8765.625388304828;8882.600282987152;9022;9025.774355641162;9201.631934722804;13054;13552.051356404956;13614.455421783017;14219.928900355846;14387.85612143907;12067.275963442222;12218;12236.449359778653;12301.876981230072;12378.938105309777;11667.474963626488;11844.70388240301;12091.274523528617;12313.13808033429;12400.007999359848;11801.114916381133;11952;12234.877651223374;12527.123101382816;12616.936915315364;9095.135962083592;9167.495787731472;9255.53722313895;9360.906390936276;9472.47901365404;10404.271700980858;12029.75940481203;12052.638420847388;12432.316222607482;12670.239785612524 07:19:15 INFO - PROCESS | 30273 | |3;mootools.html;1560.6800605875796;1586.9682606347874;1766.2581715679414;1868.1780016792611;1910.5032691500223;8674.479531228131;9345.953270233647;9422.340436169454;9923.75190620234;9925.602975880965;9162.221211197057;9789.972052934429;9805.362651427653;9839.06528879757;9861.901380986188;2490.2653717153485;2551.1963731424635;2563.884625191871;2569.9357516062155;2576.561984462646;2468.8642124683133;2533.518631460017;2539.873006349684;2545.7581529754734;2555.1823416506727;2544.0841297133015;2545.2364290712712;2556.6037264224074;2561.7310182430856;2566.371239046436;2463.9260822175365;2470.1231062972656;2478.3556275368355;2502.186789293487;2503.249025292405;2424.1273141668985;2443.8655873926928;2446.5351583199135;2454.2637208837455;2497.7377375375686;9885.950570247103;9926.751831204205;10038.749031274203;10064.748381290454;10078.092971632588;5562.9443705562835;5672.858178545569;5676.716164191774;5685.090385538294;5688.772449102073;3361.680640339164;3452.102453362119;3509.719222462222;3550.3076900004507;3568.1793187567005;1130.0863611608206;1155.555111282154;1157.242006485753;1181.4270079011671;1195.4500929572407;12047.57833475833;12117.697057573543;12121.515139394414;12224;12413.813792793115;4764.499727528629;4956.281339205795;4960.355153829979;4979.45226025137;5043.142665746832;10629.096526795309;10671;10678.839817402744;10684.626038088629;10696;5662.490375866192;5669.461401166883;5681.971590142023;5698.800541981093;5715.314162300468;4725.220338644085;5098.260752190911;5115.948840511622;5207.791688332424;5238.083335416363;4437.378766972626;4450.2434586120435;4553.157665831765;4583.862484125481;4706.929396059062;1384.3562743324403;1405.9156450612995;1409.5700811252666;1424.4231086409814;1429.9642508937359;1085.2565992295304;1097.2648325621853;1098.6813823991113;1103.707517507861;1126.7577470843808;6542.869142617121;6582.539222254492;6706.094677218611;6775.525713200127;6861.348171923759;457.4282147315855;482.94929032451086;494.2882249560621;506.7846165379699;509.956653684441;4678.836240731626;4700.318453824177;4780.856574302777;4838.2016967200725;4843.418789745253;11931.1051671125;12188.390580471118;12306.07704422172;12324.938375308066;12341.938290308492;11366.602168924048;12234.510619575118;12253.816192757116;12254.20347677398;12279.263244205376;8902.332325075646;9147.405418647766;9157.81684366309;9162.175404213653;9171.58727857248;6755.695993680198;6855.5201135922;6870.828229294308;6926.203486599105;6950.4787140965645;10628.309159904582;10860.239783215098;11261.042811361127;11276.492557835081;11419.486123124314;11149.832752508557;11227.989480946753;11228.214025018171;11232.66302010941;11400.201985860911;8069.435139540175;8408.075111737826;8591.355648326275;8605.95697021511;8787.121287871161;9612.134907858046;9635.710928672152;9692;9715.56279967389;9719.319647624598;8157.8368432632205;8178.959105204436;8230.753077407688;8263.091059983517;8378;8241.381896357678;8405.07544170153;8465.322774177928;8503.957480212559;8559.95720021396;7423.072115985502;7451.105867295742;7469.551826890404;7516.661750221362;7519.962400187964;5422.783088676409;5642.887142257214;5751.424857514216;5823.213866128188;5904.586678932434;5640.915386269291;5734.22587950638;5864.501517370901;5936.940630593812;5993.220881285405 07:19:15 INFO - PROCESS | 30273 | |4;prototype.html;357.84612616574873;358.1511816993725;359.1094086665071;359.960404355521;362.06767573498246;5717.428257174281;5876.471117599415;5879.618754404468;5888.322842873065;5919.792807251747;5661.716914154296;5756.769729210837;5766.625169363989;5797.5651826113;5845.912311315323;4551.704139230939;4562.384078149441;4576.565226303496;4586.38083858679;4587.655925805561;4526.569975852289;4535.206338890697;4545.11370282795;4547.840825571106;4553.886152846172;4475.664325175609;4520;4544.386507821451;4567.36056952027;4576.473705523874;4445.88885277869;4528.162289976349;4535;4539.296409056601;4542.613877820356;4000.179963107556;4001.659029467765;4003.099302656908;4009.538903026131;4025.657819085381;5682.801101961453;5688.914666280009;5718.571107166979;5735.483806457439;5768.711564421762;4979.601631869442;5172.172452407634;5261.815836445706;5266.15741481365;5285.894282114375;6071.51427885768;6078.240219972504;6135.3864613538735;6203.16257305267;6207.441330280251;4372.125574885035;4442.666799990014;4479.843205487825;4506.842260520899;4512.097580483884;5918.970405147947;6223.533235007348;6279.34066922971;6282.748690052438;6287.937120628781;4572.474165470947;4572.81708731647;4577.382053422812;4591.334256532784;4600.95399046012;4603.58567728906;4644.907101857944;4660.207764680013;4667.649926255476;4677.625789936865;5156.92264616031;5207.151461231284;5258.316418865523;5260.815871444558;5270.471563246692;6058.787942421994;6065.75736970525;6082.300535438393;6097.451229389376;6159.414855588801;1593.6812637472551;1608.6702226043633;1615.709172348965;1618.1989914992155;1620.8541231289005;1011.4689787861431;1052.1582733812918;1063.6383629565985;1067.514281002142;1079.80023695615;5897.380775018647;5910.143029260819;5911.231539899872;5926.970365148147;5945.40545945402;6144.385561443909;6179.536534759911;6228.844278892973;6237.719302631393;6243.625382477066;5693.68684722336;5852.239208902901;5898.292204935436;5908.291005079418;5928.517870532367;3312.354090952241;3351.6480769519335;3357.2949680567353;3362.722165577065;3366.3267346530793;6239.220097487814;6360.681965901686;6383.042543618494;6518.348165183538;6587.4071333580205;6406.647634380154;6454.19322584677;6474.935250647433;6486.481081513468;6525.608463492111;6299.905501417391;6523.347665233438;6539.967300163564;6547.508936829757;6630.171228596426;6297.756655322421;6334.651594162366;6337.524685648687;6343.9365606343345;6373.776917807854;6094.238220222473;6262.432402714865;6298.11826344303;6337.271213810471;6337.7148028337915;6499.967500162469;6524;6527.3472652734345;6535.869282614416;6537.477001839747;4405.29515277554;4449.087936972977;4467.285234362486;4467.419235499365;4498.840556532948;6157.230346206724;6272.5922815016875;6317.873642527215;6369.8726025480155;6447.451966582706;6120.17377654011;6148.877022459616;6168.629882207082;6175.69121543912;6177.598456100519;5931.6441013539325;6128.4790792783115;6160.507159427325;6164.537659675452;6184.886720390193;6124.479419249414;6126.66303353302;6146.907796382969;6178.1659475971965;6179.783707570214;6155.630662160284;6190.50475961931;6226.501879849692;6228.719707613259;6303.905441418474;5731.111677689798;6017.247844019497;6024.1265016573225;6039.365866583946;6144.678894037804 07:19:15 INFO - PROCESS | 30273 | |5;yui.html;843.8101427178882;847.9830403391933;884.5356188001299;890.612583526166;925.4493576322094;15048.17235052073;16075.115868627236;16233.756493652605;16280.592985175377;16305.83694163058;14117.71764564708;15009.174495402749;15117.622059448518;15120.319585618647;15511.224438778045;869.769511079566;897.385291075612;900.8108297257551;902.1564836877541;902.7246689759621;879.1296616349831;882.1707594860842;884.0938038510546;885.017630430222;885.9468431894074;935.854942483916;941.9387739796891;943.8584212368165;945.229637845154;945.2863088368307;929.1034152043277;931.0875342164649;933.0482907434379;933.7058826469674;935.948522831244;687.3264201082967;694.3854688600575;694.8818700820822;696.1089805049494;698.3121625199175;14889.553313400615;15327.846721532753;15359.232038398148;15375.923120384326;15404.075755454709;13849.307534623229;14401.567952961323;14412.991090623556;14580.635484112876;14754.70490590193;12395.256284622952;12449.626511204679;12451.813222801575;12588.937055314756;12943.158694684815;4130.855420060314;4132.628063474272;4169.97915010426;4330.805113769857;4339.262325404688;85269;85529;86794;86966.5651671744;87605.56197219036;11112.55549778016;11177.552897884156;11346.149038822121;11488.94255528717;11612.361320127306;22443.32670019902;22476.775232247794;22511.099556017576;22529.32412027642;22553.4361640957;9255.814883702424;9554.856677149848;9634.71095867111;9827.361221520578;10031.047050530187;5265.921011184759;5345.224942383333;5356.973215133899;5384.865378365572;5429.972850135803;3789.5263092113487;3898.1034362096875;3925.2542017016676;3931;3944.684425245973;604.1662505742136;620.3486339343671;627.3318915355223;629.8016124920726;634.9873002539923;720.3228964773097;809.7527132167128;814.4461765999177;829.9875501867476;846.7332790171076;10587.7294724632;10635.41505217205;10783.730406739738;10846.945765271123;11203.663890083311;710.928907109285;768.9807754806175;771.5255118102326;797.9840403191904;812.8130529978138;5274.7890084396195;5407.585557862716;5449.836504904859;5457.5633949283965;5499.890002200013;130134;130256.3487182558;130284;131404;135247;30952.380952380827;68159.65920170466;92436.53781731048;95199.0480095204;111894;55576.72211638997;81562.59218703987;93336.53331733434;93443;97272;10481;10596.20528460384;10885.945570272099;11104.111671066134;11275;52298.477015229364;52672.473275266755;53150.2027469596;53673.463265366845;54362.45637543574;73757.6312118436;74247;79779;80270.5986470064;80964.59517702374;13681.110727802661;14565.41738330455;15027.24863756786;15497.225138743242;15723.213839308217;14722.63193420173;15008.249587520362;15460.356533132457;15899.682006360039;16547.917260413622;14260.857391426369;14338.784918226444;14381.856181438052;14515.709685806014;14733.631659208606;15314.540563783105;16095.275712592727;16409.261583228545;16422.343106276094;16479.670406592042;14680.77978830297;14832.406703731729;15688.294026769052;15697.450589229778;15724.752347551805;12617.747645046864;12785.872141278469;12813.039022073568;12940.611781646565;13258.734825303633;12128.272303661808;13057.738845223232;13126.14680045794;13160.407781649657;13434.126781759154 07:19:15 INFO - PROCESS | 30273 | __end_tp_report 07:19:15 INFO - PROCESS | 30273 | __start_cc_report 07:19:15 INFO - PROCESS | 30273 | _x_x_mozilla_cycle_collect,326 07:19:15 INFO - PROCESS | 30273 | __end_cc_report 07:19:15 INFO - PROCESS | 30273 | __startTimestamp1472739555637__endTimestamp 07:19:15 INFO - PROCESS | 30273 | 07:19:15 INFO - PROCESS | 30273 | ------- Summary: start ------- 07:19:15 INFO - PROCESS | 30273 | Number of tests: 6 07:19:15 INFO - PROCESS | 30273 | 07:19:15 INFO - PROCESS | 30273 | [#0] dojo.html Cycles:165 Average:5794.76 Median:6023.61 stddev:664.23 (11.0%) stddev-sans-first:636.30 07:19:15 INFO - PROCESS | 30273 | Values: 3279.8 3288.5 3305.6 3308.6 3322.8 5758.7 5837.0 5853.2 5868.7 5870.5 5866.6 5917.7 5925.9 5933.8 5935.9 5333.8 5363.1 5391.6 5429.0 5429.4 5264.4 5282.3 5324.4 5343.3 5387.7 5335.7 5359.5 5364.7 5376.0 5441.4 5408.9 5427.1 5433.3 5445.9 5468.2 5074.3 5128.1 5151.0 5314.8 5338.4 5670.9 5726.3 5747.3 5750.1 5782.1 6119.4 6158.2 6163.1 6170.4 6179.0 6119.9 6150.4 6194.9 6226.9 6244.2 5900.8 5905.3 5906.4 5914.8 5941.3 6417.2 6441.6 6449.4 6458.5 6472.0 6004.4 6023.6 6058.2 6111.8 6195.5 6238.7 6245.8 6261.1 6262.9 6263.4 6211.0 6220.5 6250.1 6258.2 6262.2 6025.8 6081.1 6107.8 6229.0 6241.3 4741.8 4745.9 4752.3 4767.6 4769.0 4220.2 4223.4 4228.4 4248.4 4249.1 5795.3 5859.7 5883.6 5913.4 5988.9 6201.6 6237.5 6241.0 6252.2 6291.5 5928.1 5951.7 5964.3 5976.7 5978.8 5260.7 5336.9 5370.2 5375.1 5375.4 6474.8 6531.0 6542.3 6543.6 6552.5 6300.3 6364.5 6366.1 6371.9 6374.2 6268.7 6281.2 6296.5 6495.2 6550.0 6125.5 6136.5 6139.4 6151.8 6171.2 6204.1 6235.4 6241.7 6246.8 6255.3 5938.3 5956.6 5969.8 6062.7 6089.0 6038.2 6045.7 6050.2 6067.9 6088.0 5999.0 6009.9 6056.3 6067.3 6069.5 6026.8 6033.0 6090.9 6097.3 6114.5 6010.4 6024.3 6070.3 6073.2 6084.3 07:19:15 INFO - PROCESS | 30273 | 07:19:15 INFO - PROCESS | 30273 | [#1] ext.html Cycles:180 Average:67675.93 Median:24784.34 stddev:140295.48 (566.1%) stddev-sans-first:140598.59 07:19:15 INFO - PROCESS | 30273 | Values: 573.7 645.5 646.0 655.6 684.7 41354.0 45998.3 46017.1 46105.3 46231.0 45835.0 46139.3 46183.8 46416.1 46475.1 9068.8 9105.0 9115.5 9213.5 9231.8 9076.9 9187.9 9231.0 9248.7 9261.0 9262.1 9304.0 9319.0 9320.3 9328.7 9207.8 9231.1 9234.6 9246.7 9279.7 9110.5 9162.0 9180.1 9185.7 9191.1 43453.6 43594.8 43747.0 43797.8 43816.6 3785.2 3809.3 3814.8 3865.3 3969.5 34816.1 35092.5 35199.8 35523.1 36877.3 4498.4 4499.9 4510.9 4515.3 4527.9 379734.0 383279.0 383565.0 384339.0 385332.0 37489.8 38244.6 56023.4 56216.4 56608.7 40212.4 40233.8 40260.6 40294.0 40602.4 17609.6 17613.1 17673.6 17675.3 17795.6 14686.3 14847.9 14872.0 14899.4 14918.0 9001.9 9139.0 9200.3 9210.6 9288.6 3495.3 3510.2 3576.4 3583.6 3636.1 1089.8 1092.4 1094.9 1179.9 1205.2 34941.5 35375.6 35401.5 35425.8 35566.8 1176.6 1198.4 1223.9 1244.0 1257.1 18114.1 18118.7 18121.2 18140.4 18207.5 615974.0 619000.0 619977.0 626961.0 630980.0 63586.7 64428.4 64520.0 64671.7 64717.4 1455.4 1491.3 1499.8 1505.7 1506.9 33154.3 33323.5 33592.0 33735.8 34122.5 55991.2 61086.4 62812.4 62835.0 68201.7 540435.0 541111.0 547824.0 548482.0 551179.0 48757.5 48992.0 49089.5 49099.8 49420.5 52740.0 52973.0 53391.7 53590.0 53628.5 44445.6 44631.7 46523.5 46562.8 47870.5 45068.8 45093.8 45450.0 45876.6 46067.0 39081.4 43940.6 44149.8 44592.8 45662.0 38681.2 39371.4 39767.4 39888.2 40055.4 29883.7 30192.5 30255.2 30379.9 30510.5 07:19:15 INFO - PROCESS | 30273 | 07:19:15 INFO - PROCESS | 30273 | [#2] jquery.html Cycles:180 Average:30550.23 Median:2122.92 stddev:85193.48 (4013.0%) stddev-sans-first:85402.42 07:19:15 INFO - PROCESS | 30273 | Values: 415.2 423.5 427.9 434.7 445.9 19779.6 20998.0 21505.7 21618.0 21670.0 19517.2 20066.2 20953.1 21065.4 21165.4 5364.8 5585.7 5614.6 5623.0 5633.0 5484.3 5495.8 5528.6 5533.9 5541.2 5425.1 5541.8 5546.1 5548.5 5568.1 5464.7 5995.5 6037.7 6038.3 6046.8 5211.8 5426.7 5436.6 5452.6 5454.6 14629.6 14892.9 15138.4 15319.7 15345.4 2445.1 2488.9 2834.3 3103.8 3115.4 10911.4 10939.8 10945.5 10946.9 11335.8 2046.5 2062.1 2083.6 2091.8 2093.7 87332.6 87988.6 90041.0 90440.1 91508.0 10599.8 10653.3 10856.5 11019.8 11216.8 15176.9 15187.0 15192.3 15206.8 15208.3 8253.0 8544.9 8618.4 8754.1 8771.4 8704.1 8750.1 8799.2 8921.9 8938.2 4405.7 4610.3 4614.8 4629.4 4631.5 2040.9 2118.7 2127.1 2156.8 2175.7 1007.4 1012.7 1039.0 1057.4 1107.1 20450.3 20693.0 20823.8 20947.4 20980.9 1011.4 1038.9 1051.6 1059.4 1095.8 10735.2 11408.4 11567.9 11625.2 11668.9 503027.0 515324.0 515925.0 519957.0 521537.0 97538.0 99368.0 99656.0 100721.0 103730.0 1002.1 1019.9 1029.8 1031.8 1052.9 17805.5 18511.2 18539.3 18683.6 19007.5 38660.6 45304.3 46959.8 47243.3 48673.8 62891.0 64020.0 64341.4 64606.0 65124.7 8765.6 8882.6 9022.0 9025.8 9201.6 13054.0 13552.1 13614.5 14219.9 14387.9 12067.3 12218.0 12236.4 12301.9 12378.9 11667.5 11844.7 12091.3 12313.1 12400.0 11801.1 11952.0 12234.9 12527.1 12616.9 9095.1 9167.5 9255.5 9360.9 9472.5 10404.3 12029.8 12052.6 12432.3 12670.2 07:19:15 INFO - PROCESS | 30273 | 07:19:15 INFO - PROCESS | 30273 | [#3] mootools.html Cycles:180 Average:6328.23 Median:4716.07 stddev:3589.76 (76.1%) stddev-sans-first:3581.95 07:19:15 INFO - PROCESS | 30273 | Values: 1560.7 1587.0 1766.3 1868.2 1910.5 8674.5 9346.0 9422.3 9923.8 9925.6 9162.2 9790.0 9805.4 9839.1 9861.9 2490.3 2551.2 2563.9 2569.9 2576.6 2468.9 2533.5 2539.9 2545.8 2555.2 2544.1 2545.2 2556.6 2561.7 2566.4 2463.9 2470.1 2478.4 2502.2 2503.2 2424.1 2443.9 2446.5 2454.3 2497.7 9886.0 9926.8 10038.7 10064.7 10078.1 5562.9 5672.9 5676.7 5685.1 5688.8 3361.7 3452.1 3509.7 3550.3 3568.2 1130.1 1155.6 1157.2 1181.4 1195.5 12047.6 12117.7 12121.5 12224.0 12413.8 4764.5 4956.3 4960.4 4979.5 5043.1 10629.1 10671.0 10678.8 10684.6 10696.0 5662.5 5669.5 5682.0 5698.8 5715.3 4725.2 5098.3 5115.9 5207.8 5238.1 4437.4 4450.2 4553.2 4583.9 4706.9 1384.4 1405.9 1409.6 1424.4 1430.0 1085.3 1097.3 1098.7 1103.7 1126.8 6542.9 6582.5 6706.1 6775.5 6861.3 457.4 482.9 494.3 506.8 510.0 4678.8 4700.3 4780.9 4838.2 4843.4 11931.1 12188.4 12306.1 12324.9 12341.9 11366.6 12234.5 12253.8 12254.2 12279.3 8902.3 9147.4 9157.8 9162.2 9171.6 6755.7 6855.5 6870.8 6926.2 6950.5 10628.3 10860.2 11261.0 11276.5 11419.5 11149.8 11228.0 11228.2 11232.7 11400.2 8069.4 8408.1 8591.4 8606.0 8787.1 9612.1 9635.7 9692.0 9715.6 9719.3 8157.8 8179.0 8230.8 8263.1 8378.0 8241.4 8405.1 8465.3 8504.0 8560.0 7423.1 7451.1 7469.6 7516.7 7520.0 5422.8 5642.9 5751.4 5823.2 5904.6 5640.9 5734.2 5864.5 5936.9 5993.2 07:19:15 INFO - PROCESS | 30273 | 07:19:15 INFO - PROCESS | 30273 | [#4] prototype.html Cycles:180 Average:5176.58 Median:5883.97 stddev:1515.12 (25.7%) stddev-sans-first:1475.57 07:19:15 INFO - PROCESS | 30273 | Values: 357.8 358.2 359.1 360.0 362.1 5717.4 5876.5 5879.6 5888.3 5919.8 5661.7 5756.8 5766.6 5797.6 5845.9 4551.7 4562.4 4576.6 4586.4 4587.7 4526.6 4535.2 4545.1 4547.8 4553.9 4475.7 4520.0 4544.4 4567.4 4576.5 4445.9 4528.2 4535.0 4539.3 4542.6 4000.2 4001.7 4003.1 4009.5 4025.7 5682.8 5688.9 5718.6 5735.5 5768.7 4979.6 5172.2 5261.8 5266.2 5285.9 6071.5 6078.2 6135.4 6203.2 6207.4 4372.1 4442.7 4479.8 4506.8 4512.1 5919.0 6223.5 6279.3 6282.7 6287.9 4572.5 4572.8 4577.4 4591.3 4601.0 4603.6 4644.9 4660.2 4667.6 4677.6 5156.9 5207.2 5258.3 5260.8 5270.5 6058.8 6065.8 6082.3 6097.5 6159.4 1593.7 1608.7 1615.7 1618.2 1620.9 1011.5 1052.2 1063.6 1067.5 1079.8 5897.4 5910.1 5911.2 5927.0 5945.4 6144.4 6179.5 6228.8 6237.7 6243.6 5693.7 5852.2 5898.3 5908.3 5928.5 3312.4 3351.6 3357.3 3362.7 3366.3 6239.2 6360.7 6383.0 6518.3 6587.4 6406.6 6454.2 6474.9 6486.5 6525.6 6299.9 6523.3 6540.0 6547.5 6630.2 6297.8 6334.7 6337.5 6343.9 6373.8 6094.2 6262.4 6298.1 6337.3 6337.7 6500.0 6524.0 6527.3 6535.9 6537.5 4405.3 4449.1 4467.3 4467.4 4498.8 6157.2 6272.6 6317.9 6369.9 6447.5 6120.2 6148.9 6168.6 6175.7 6177.6 5931.6 6128.5 6160.5 6164.5 6184.9 6124.5 6126.7 6146.9 6178.2 6179.8 6155.6 6190.5 6226.5 6228.7 6303.9 5731.1 6017.2 6024.1 6039.4 6144.7 07:19:15 INFO - PROCESS | 30273 | 07:19:15 INFO - PROCESS | 30273 | [#5] yui.html Cycles:180 Average:21661.56 Median:3928.13 stddev:31214.94 (794.7%) stddev-sans-first:31263.36 07:19:15 INFO - PROCESS | 30273 | Values: 843.8 848.0 884.5 890.6 925.4 15048.2 16075.1 16233.8 16280.6 16305.8 14117.7 15009.2 15117.6 15120.3 15511.2 869.8 897.4 900.8 902.2 902.7 879.1 882.2 884.1 885.0 885.9 935.9 941.9 943.9 945.2 945.3 929.1 931.1 933.0 933.7 935.9 687.3 694.4 694.9 696.1 698.3 14889.6 15327.8 15359.2 15375.9 15404.1 13849.3 14401.6 14413.0 14580.6 14754.7 12395.3 12449.6 12451.8 12588.9 12943.2 4130.9 4132.6 4170.0 4330.8 4339.3 85269.0 85529.0 86794.0 86966.6 87605.6 11112.6 11177.6 11346.1 11488.9 11612.4 22443.3 22476.8 22511.1 22529.3 22553.4 9255.8 9554.9 9634.7 9827.4 10031.0 5265.9 5345.2 5357.0 5384.9 5430.0 3789.5 3898.1 3925.3 3931.0 3944.7 604.2 620.3 627.3 629.8 635.0 720.3 809.8 814.4 830.0 846.7 10587.7 10635.4 10783.7 10846.9 11203.7 710.9 769.0 771.5 798.0 812.8 5274.8 5407.6 5449.8 5457.6 5499.9 130134.0 130256.3 130284.0 131404.0 135247.0 30952.4 68159.7 92436.5 95199.0 111894.0 55576.7 81562.6 93336.5 93443.0 97272.0 10481.0 10596.2 10885.9 11104.1 11275.0 52298.5 52672.5 53150.2 53673.5 54362.5 73757.6 74247.0 79779.0 80270.6 80964.6 13681.1 14565.4 15027.2 15497.2 15723.2 14722.6 15008.2 15460.4 15899.7 16547.9 14260.9 14338.8 14381.9 14515.7 14733.6 15314.5 16095.3 16409.3 16422.3 16479.7 14680.8 14832.4 15688.3 15697.5 15724.8 12617.7 12785.9 12813.0 12940.6 13258.7 12128.3 13057.7 13126.1 13160.4 13434.1 07:19:15 INFO - PROCESS | 30273 | -------- Summary: end -------- 07:19:15 INFO - PROCESS | 30273 | 07:19:16 INFO - TEST-INFO | 30273: exit 0 07:19:16 INFO - TEST-OK | dromaeo_css | took 1109910ms 07:19:16 INFO - TEST-START | kraken 07:19:16 INFO - operating with platform_type : linux_ 07:19:16 INFO - Initialising browser for kraken test... 07:19:16 INFO - TEST-INFO | started process 30542 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTOABrS/profile http://localhost:57282/getInfo.html) 07:19:20 INFO - PROCESS | 30542 | __metrics Screen width/height:1600/1200 07:19:20 INFO - PROCESS | 30542 | colorDepth:24 07:19:20 INFO - PROCESS | 30542 | Browser inner width/height: 1024/697 07:19:20 INFO - PROCESS | 30542 | __metrics 07:19:20 ERROR - PROCESS | 30542 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 07:19:21 INFO - TEST-INFO | 30542: exit 0 07:19:21 INFO - Browser initialized. 07:19:21 INFO - Running cycle 1/1 for kraken test... 07:19:21 INFO - TEST-INFO | started process 30667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTOABrS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:33 INFO - PROCESS | 30667 | RSS: Main: 152563712 07:19:33 INFO - PROCESS | 30667 | 07:19:35 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57282/tests/kraken/driver.html?testName=audio-beat-detection) 07:19:36 INFO - PROCESS | 30667 | RSS: Main: 158277632 07:19:36 INFO - PROCESS | 30667 | 07:19:39 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57282/tests/kraken/driver.html?testName=audio-dft) 07:19:39 INFO - PROCESS | 30667 | RSS: Main: 156827648 07:19:39 INFO - PROCESS | 30667 | 07:19:42 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57282/tests/kraken/driver.html?testName=audio-fft) 07:19:43 INFO - PROCESS | 30667 | RSS: Main: 157298688 07:19:43 INFO - PROCESS | 30667 | 07:19:45 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57282/tests/kraken/driver.html?testName=audio-oscillator) 07:19:45 INFO - PROCESS | 30667 | RSS: Main: 156950528 07:19:45 INFO - PROCESS | 30667 | 07:19:48 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:19:48 INFO - PROCESS | 30667 | RSS: Main: 156942336 07:19:48 INFO - PROCESS | 30667 | 07:19:51 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57282/tests/kraken/driver.html?testName=imaging-darkroom) 07:19:52 INFO - PROCESS | 30667 | RSS: Main: 156762112 07:19:52 INFO - PROCESS | 30667 | 07:19:55 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57282/tests/kraken/driver.html?testName=imaging-desaturate) 07:19:56 INFO - PROCESS | 30667 | RSS: Main: 156508160 07:19:56 INFO - PROCESS | 30667 | 07:19:59 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57282/tests/kraken/driver.html?testName=json-parse-financial) 07:20:00 INFO - PROCESS | 30667 | RSS: Main: 157478912 07:20:00 INFO - PROCESS | 30667 | 07:20:01 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:20:02 INFO - PROCESS | 30667 | RSS: Main: 156794880 07:20:02 INFO - PROCESS | 30667 | 07:20:03 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57282/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:20:04 INFO - PROCESS | 30667 | RSS: Main: 156909568 07:20:04 INFO - PROCESS | 30667 | 07:20:06 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:20:07 INFO - PROCESS | 30667 | RSS: Main: 157814784 07:20:07 INFO - PROCESS | 30667 | 07:20:09 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:20:10 INFO - PROCESS | 30667 | RSS: Main: 158105600 07:20:10 INFO - PROCESS | 30667 | 07:20:13 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:20:13 INFO - PROCESS | 30667 | RSS: Main: 158121984 07:20:13 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | Cycle 1(1): loaded http://localhost:57282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57282/tests/kraken/driver.html?testName=ai-astar) 07:20:15 INFO - PROCESS | 30667 | RSS: Main: 158732288 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | __start_tp_report 07:20:15 INFO - PROCESS | 30667 | _x_x_mozilla_page_load 07:20:15 INFO - PROCESS | 30667 | _x_x_mozilla_page_load_details 07:20:15 INFO - PROCESS | 30667 | |i|pagename|runs| 07:20:15 INFO - PROCESS | 30667 | |0;ai-astar;102;111;106;110;112;112;112;112;110;110 07:20:15 INFO - PROCESS | 30667 | |1;audio-beat-detection;152;118;142;176;117;151;149;117;177;116 07:20:15 INFO - PROCESS | 30667 | |2;audio-dft;162;163;164;162;162;161;163;161;162;161 07:20:15 INFO - PROCESS | 30667 | |3;audio-fft;74;76;77;171;76;76;76;75;171;76 07:20:15 INFO - PROCESS | 30667 | |4;audio-oscillator;83;84;158;84;84;83;141;82;84;144 07:20:15 INFO - PROCESS | 30667 | |5;imaging-gaussian-blur;117;118;118;121;117;118;118;118;117;117 07:20:15 INFO - PROCESS | 30667 | |6;imaging-darkroom;147;147;147;146;147;147;147;147;146;147 07:20:15 INFO - PROCESS | 30667 | |7;imaging-desaturate;95;96;95;95;95;95;95;96;95;95 07:20:15 INFO - PROCESS | 30667 | |8;json-parse-financial;51;54;51;51;52;51;51;52;52;55 07:20:15 INFO - PROCESS | 30667 | |9;json-stringify-tinderbox;64;61;60;61;60;60;61;60;62;61 07:20:15 INFO - PROCESS | 30667 | |10;stanford-crypto-aes;77;73;74;75;72;83;74;73;78;72 07:20:15 INFO - PROCESS | 30667 | |11;stanford-crypto-ccm;125;127;119;118;118;137;129;142;165;120 07:20:15 INFO - PROCESS | 30667 | |12;stanford-crypto-pbkdf2;156;156;156;153;152;158;154;150;151;154 07:20:15 INFO - PROCESS | 30667 | |13;stanford-crypto-sha256-iterative;63;64;70;63;66;63;62;65;62;65 07:20:15 INFO - PROCESS | 30667 | __end_tp_report 07:20:15 INFO - PROCESS | 30667 | __start_cc_report 07:20:15 INFO - PROCESS | 30667 | _x_x_mozilla_cycle_collect,885 07:20:15 INFO - PROCESS | 30667 | __end_cc_report 07:20:15 INFO - PROCESS | 30667 | __startTimestamp1472739615597__endTimestamp 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | ------- Summary: start ------- 07:20:15 INFO - PROCESS | 30667 | Number of tests: 14 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.50 stddev:3.27 (2.9%) stddev-sans-first:1.94 07:20:15 INFO - PROCESS | 30667 | Values: 102.0 111.0 106.0 110.0 112.0 112.0 112.0 112.0 110.0 110.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:150.00 stddev:23.83 (15.9%) stddev-sans-first:24.97 07:20:15 INFO - PROCESS | 30667 | Values: 152.0 118.0 142.0 176.0 117.0 151.0 149.0 117.0 177.0 116.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 07:20:15 INFO - PROCESS | 30667 | Values: 162.0 163.0 164.0 162.0 162.0 161.0 163.0 161.0 162.0 161.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#3] audio-fft Cycles:10 Average:94.80 Median:76.00 stddev:40.17 (52.9%) stddev-sans-first:41.89 07:20:15 INFO - PROCESS | 30667 | Values: 74.0 76.0 77.0 171.0 76.0 76.0 76.0 75.0 171.0 76.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:83.50 stddev:31.33 (37.5%) stddev-sans-first:32.41 07:20:15 INFO - PROCESS | 30667 | Values: 83.0 84.0 158.0 84.0 84.0 83.0 141.0 82.0 84.0 144.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:1.20 (1.0%) stddev-sans-first:1.22 07:20:15 INFO - PROCESS | 30667 | Values: 117.0 118.0 118.0 121.0 117.0 118.0 118.0 118.0 117.0 117.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 07:20:15 INFO - PROCESS | 30667 | Values: 147.0 147.0 147.0 146.0 147.0 147.0 147.0 147.0 146.0 147.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:20:15 INFO - PROCESS | 30667 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:1.41 (2.7%) stddev-sans-first:1.45 07:20:15 INFO - PROCESS | 30667 | Values: 51.0 54.0 51.0 51.0 52.0 51.0 51.0 52.0 52.0 55.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:61.00 stddev:1.25 (2.0%) stddev-sans-first:0.71 07:20:15 INFO - PROCESS | 30667 | Values: 64.0 61.0 60.0 61.0 60.0 60.0 61.0 60.0 62.0 61.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:74.50 stddev:3.41 (4.6%) stddev-sans-first:3.55 07:20:15 INFO - PROCESS | 30667 | Values: 77.0 73.0 74.0 75.0 72.0 83.0 74.0 73.0 78.0 72.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#11] stanford-crypto-ccm Cycles:10 Average:130.00 Median:128.00 stddev:14.76 (11.5%) stddev-sans-first:15.55 07:20:15 INFO - PROCESS | 30667 | Values: 125.0 127.0 119.0 118.0 118.0 137.0 129.0 142.0 165.0 120.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.00 Median:155.00 stddev:2.54 (1.6%) stddev-sans-first:2.59 07:20:15 INFO - PROCESS | 30667 | Values: 156.0 156.0 156.0 153.0 152.0 158.0 154.0 150.0 151.0 154.0 07:20:15 INFO - PROCESS | 30667 | 07:20:15 INFO - PROCESS | 30667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.50 stddev:2.41 (3.7%) stddev-sans-first:2.51 07:20:15 INFO - PROCESS | 30667 | Values: 63.0 64.0 70.0 63.0 66.0 63.0 62.0 65.0 62.0 65.0 07:20:15 INFO - PROCESS | 30667 | -------- Summary: end -------- 07:20:15 INFO - PROCESS | 30667 | 07:20:16 INFO - TEST-INFO | 30667: exit 0 07:20:16 INFO - TEST-OK | kraken | took 60155ms 07:20:16 INFO - SUITE-END | took 1170s 07:20:16 INFO - Completed test suite (00:19:30) 07:20:16 INFO - javascript benchmark 07:20:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7754.879876960314, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3279.8196099214547, 3288.539604455375, 3305.6363799981996, 3308.586426696663, 3322.8172450515226, 5758.654480731154, 5837.036888913335, 5853.209816674747, 5868.677222752747, 5870.471657550819, 5866.560007999396, 5917.704114794265, 5925.911111333334, 5933.762649494015, 5935.940640593592, 5333.839984800453, 5363.061464243761, 5391.595630327722, 5428.972855135738, 5429.4027656957705, 5264.39459462161, 5282.339707536558, 5324.387695415019, 5343.251944727741, 5387.676739395649, 5335.71942733745, 5359.517643412092, 5364.7317634118335, 5376.032314183445, 5441.428649991753, 5408.945910540903, 5427.104527752916, 5433.347998240237, 5445.918311225335, 5468.234447177399, 5074.31496747938, 5128.051310507531, 5151.0, 5314.787408503656, 5338.359396872362, 5670.943290567083, 5726.31284245889, 5747.310322761254, 5750.051241545182, 5782.132680097977, 6119.388061193889, 6158.1686472326555, 6163.137160797447, 6170.413810687977, 6179.0, 6119.938800611982, 6150.384961503813, 6194.938050619481, 6226.87546249077, 6244.188255526798, 5900.763969441175, 5905.320888097881, 5906.350301466835, 5914.822555323347, 5941.257342832103, 6417.2299324081405, 6441.645709486023, 6449.355064493513, 6458.54790164684, 6471.967640161769, 6004.39956004396, 6023.608465449732, 6058.242719660043, 6111.816645500642, 6195.504359651217, 6238.719257633417, 6245.750169993149, 6261.123442718023, 6262.906056409158, 6263.4362907337645, 6211.0, 6220.471259942956, 6250.093736408196, 6258.186435763413, 6262.2172228471445, 6025.758969641253, 6081.057436097323, 6107.816765497042, 6229.034499652561, 6241.282252540925, 4741.786619602126, 4745.857624271277, 4752.334673145762, 4767.61859051275, 4769.022350418156, 4220.2192594370135, 4223.366495025709, 4228.4080228768, 4248.4052232687445, 4249.086446414036, 5795.304563452329, 5859.677717725481, 5883.61756485827, 5913.379095195029, 5988.850278742979, 6201.56589038772, 6237.500999919996, 6241.0, 6252.155958945576, 6291.496680265568, 5928.140419639129, 5951.672658003851, 5964.314103878023, 5976.671283079472, 5978.8206353809455, 5260.736963151904, 5336.946630533645, 5370.248165256867, 5375.059364611177, 5375.435579264199, 6474.773382931575, 6530.967345163243, 6542.280349161589, 6543.607383557001, 6552.50856185788, 6300.30696623371, 6364.4908407326375, 6366.140571023038, 6371.936280637135, 6374.235091789015, 6268.6552239625435, 6281.246250449975, 6296.496280297473, 6495.1556297681, 6549.96725016391, 6125.540584456273, 6136.539759518143, 6139.3553676863285, 6151.784687535915, 6171.197744293213, 6204.069389591597, 6235.438810507076, 6241.719122639402, 6246.843828904313, 6255.280642725963, 5938.317093534299, 5956.553258505716, 5969.791057312973, 6062.696865156813, 6089.0, 6038.184845045863, 6045.667488288011, 6050.2437195350585, 6067.908981365372, 6088.0, 5999.0, 6009.87980240384, 6056.273247210363, 6067.332593414632, 6069.514438844793, 6026.789062382795, 6033.0, 6090.9390906090375, 6097.2683278006925, 6114.510839132948, 6010.429009244115, 6024.33732289439, 6070.332263451105, 6073.210482637229, 6084.300305464928], "value": 5748.76421223627, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.7360814025548, 645.4642646603319, 646.0212777641871, 655.5935320101536, 684.7089986755634, 41354.0, 45998.310025349565, 46017.07965840685, 46105.30842037372, 46231.0, 45835.0, 46139.30791038137, 46183.76908115455, 46416.07167856641, 46475.07049859001, 9068.773280667987, 9104.954475227614, 9115.544222788867, 9213.493257870814, 9231.815363692756, 9076.8638470423, 9187.897452305733, 9231.030741772118, 9248.722538323827, 9260.953695231514, 9262.120098590623, 9303.953480232589, 9319.0, 9320.347575669706, 9328.720138395858, 9207.815843683122, 9231.123043310874, 9234.584443700049, 9246.722598322061, 9279.72160835176, 9110.498922559256, 9162.0, 9180.127887850644, 9185.724428267162, 9191.126842949909, 43453.565464345265, 43594.78202608998, 43747.0, 43797.78101109506, 43816.561834381566, 3785.2051069275485, 3809.257194847006, 3814.771113733185, 3865.2524530250735, 3969.5435024972207, 34816.12959676003, 35092.473612895825, 35199.82400087983, 35523.11192220189, 36877.26245475102, 4498.3927169832305, 4499.910001799978, 4510.909781804346, 4515.254982927813, 4527.932081018787, 379734.0, 383279.0, 383565.0, 384339.0, 385332.0, 37489.81255093707, 38244.61755382411, 56023.439765602634, 56216.43783562194, 56608.71695641578, 40212.39681404781, 40233.79883100566, 40260.59739402627, 40294.0, 40602.390964135564, 17609.647807043788, 17613.119344032748, 17673.646527069388, 17675.29298828058, 17795.555111122067, 14686.265686715622, 14847.925760371128, 14872.0, 14899.404023839143, 14918.030328028643, 9001.909980900238, 9138.954305228564, 9200.30997675177, 9210.585523651453, 9288.582013809395, 3495.2834668892815, 3510.227749895019, 3576.40989236773, 3583.605803361628, 3636.127329440968, 1089.8201796703613, 1092.415557676639, 1094.8631421072366, 1179.9469023893946, 1205.1804772754435, 34941.47587786185, 35375.64624353775, 35401.46897796535, 35425.822870886, 35566.822165889, 1176.564671071709, 1198.3708553009565, 1223.9265644061206, 1243.9502419903104, 1257.1388598809845, 18114.094295285184, 18118.728219076726, 18121.23135512523, 18140.365087221882, 18207.45377638673, 615974.0, 619000.0, 619977.0, 626961.0, 630980.0, 63586.68206658937, 64428.35571644317, 64520.0, 64671.67664161649, 64717.352826471135, 1455.44693016653, 1491.3289019941287, 1499.7975273337959, 1505.7214415333199, 1506.909585424878, 33154.33691326208, 33323.500147497325, 33592.0, 33735.83132084422, 34122.48816267808, 55991.16013259886, 61086.38913610807, 62812.37187628065, 62835.0, 68201.65899170472, 540435.0, 541111.0, 547824.0, 548482.0, 551179.0, 48757.51242487672, 48992.0, 49089.50910490992, 49099.75450122727, 49420.50579494159, 52740.0, 52973.0, 53391.73304133455, 53590.0, 53628.463715363905, 44445.55554444414, 44631.73396485455, 46523.534764653275, 46562.767186165205, 47870.521294788, 45068.77465612651, 45093.774531127136, 45450.0, 45876.58207484979, 46067.0, 39081.41377879391, 43940.560594393646, 44149.779251104825, 44592.77703611461, 45662.0, 38681.2263754729, 39371.409428858016, 39767.40348894711, 39888.2022359557, 40055.39916901191, 29883.70116298809, 30192.5471117929, 30255.243618909702, 30379.936702215316, 30510.542341865334], "value": 20768.143396996096, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.2338934665541, 423.48546515983077, 427.87591598436455, 434.7391565060964, 445.944256967879, 19779.604407911833, 20998.0, 21505.67741483877, 21618.0, 21670.0, 19517.21931122757, 20066.197352105897, 20953.05711242994, 21065.368038958855, 21165.365039048815, 5364.758585863636, 5585.692786896719, 5614.606977511576, 5623.0, 5632.971835140818, 5484.2596249506205, 5495.807646732365, 5528.55771538276, 5533.944660553383, 5541.168824676291, 5425.104857698475, 5541.806036788713, 5546.084895992157, 5548.500634942854, 5568.136938774496, 5464.726763661801, 5995.460408563206, 6037.667928263943, 6038.27540695115, 6046.758129674829, 5211.765470553834, 5426.701531415771, 5436.619436639437, 5452.563794896399, 5454.59090568205, 14629.561113166623, 14892.925535372255, 15138.394464221417, 15319.693606127927, 15345.386184552604, 2445.0708730682404, 2488.925332240036, 2834.3055951292067, 3103.7516998640053, 3115.3613509230554, 10911.399873006982, 10939.83590246139, 10945.507452164671, 10946.945265273702, 11335.773284534263, 2046.5292982614083, 2062.092679221138, 2083.593699228651, 2091.8117369436823, 2093.71734815801, 87332.56333718354, 87988.56005719994, 90041.0, 90440.09559904448, 91508.0, 10599.841002384816, 10653.307535010199, 10856.511456984455, 11019.83470247947, 11216.775664486664, 15176.924115379352, 15187.0, 15192.316345764468, 15206.847931520764, 15208.315625796644, 8252.958735206406, 8544.914550854412, 8618.39671223021, 8754.080821513777, 8771.38600297973, 8704.086070962458, 8750.081241469554, 8799.164079412445, 8921.866172007425, 8938.195562399414, 4405.669574781904, 4610.331501932268, 4614.769261536977, 4629.421322334708, 4631.467381251132, 2040.8673436226597, 2118.724565806466, 2127.1172463427815, 2156.773538778437, 2175.706279652258, 1007.4091718898068, 1012.7063151686076, 1039.033698660238, 1057.376148072641, 1107.0977153619774, 20450.284240051526, 20693.0, 20823.791762082186, 20947.37157885266, 20980.89509552473, 1011.4083261292227, 1038.8857225705167, 1051.5898799468214, 1059.364381371171, 1095.7863216672672, 10735.194860385502, 11408.372539510407, 11567.942160289145, 11625.24435911663, 11668.94165529167, 503027.0, 515324.0, 515925.0, 519957.0, 521537.0, 97538.0, 99368.0, 99656.0, 100721.0, 103730.0, 1002.1231422505308, 1019.8929112443086, 1029.7631544744602, 1031.7730099378125, 1052.85786418834, 17805.46583602494, 18511.166997484877, 18539.258429662663, 18683.626327473103, 19007.524811879815, 38660.6133938657, 45304.32043519416, 46959.765201173774, 47243.29135062908, 48673.75663121662, 62891.0, 64020.0, 64341.35658643354, 64606.0, 65124.674376627816, 8765.625388304828, 8882.600282987152, 9022.0, 9025.774355641162, 9201.631934722804, 13054.0, 13552.051356404956, 13614.455421783017, 14219.928900355846, 14387.85612143907, 12067.275963442222, 12218.0, 12236.449359778653, 12301.876981230072, 12378.938105309777, 11667.474963626488, 11844.70388240301, 12091.274523528617, 12313.13808033429, 12400.007999359848, 11801.114916381133, 11952.0, 12234.877651223374, 12527.123101382816, 12616.936915315364, 9095.135962083592, 9167.495787731472, 9255.53722313895, 9360.906390936276, 9472.47901365404, 10404.271700980858, 12029.75940481203, 12052.638420847388, 12432.316222607482, 12670.239785612524], "value": 9556.374775586377, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1560.6800605875796, 1586.9682606347874, 1766.2581715679414, 1868.1780016792611, 1910.5032691500223, 8674.479531228131, 9345.953270233647, 9422.340436169454, 9923.75190620234, 9925.602975880965, 9162.221211197057, 9789.972052934429, 9805.362651427653, 9839.06528879757, 9861.901380986188, 2490.2653717153485, 2551.1963731424635, 2563.884625191871, 2569.9357516062155, 2576.561984462646, 2468.8642124683133, 2533.518631460017, 2539.873006349684, 2545.7581529754734, 2555.1823416506727, 2544.0841297133015, 2545.2364290712712, 2556.6037264224074, 2561.7310182430856, 2566.371239046436, 2463.9260822175365, 2470.1231062972656, 2478.3556275368355, 2502.186789293487, 2503.249025292405, 2424.1273141668985, 2443.8655873926928, 2446.5351583199135, 2454.2637208837455, 2497.7377375375686, 9885.950570247103, 9926.751831204205, 10038.749031274203, 10064.748381290454, 10078.092971632588, 5562.9443705562835, 5672.858178545569, 5676.716164191774, 5685.090385538294, 5688.772449102073, 3361.680640339164, 3452.102453362119, 3509.719222462222, 3550.3076900004507, 3568.1793187567005, 1130.0863611608206, 1155.555111282154, 1157.242006485753, 1181.4270079011671, 1195.4500929572407, 12047.57833475833, 12117.697057573543, 12121.515139394414, 12224.0, 12413.813792793115, 4764.499727528629, 4956.281339205795, 4960.355153829979, 4979.45226025137, 5043.142665746832, 10629.096526795309, 10671.0, 10678.839817402744, 10684.626038088629, 10696.0, 5662.490375866192, 5669.461401166883, 5681.971590142023, 5698.800541981093, 5715.314162300468, 4725.220338644085, 5098.260752190911, 5115.948840511622, 5207.791688332424, 5238.083335416363, 4437.378766972626, 4450.2434586120435, 4553.157665831765, 4583.862484125481, 4706.929396059062, 1384.3562743324403, 1405.9156450612995, 1409.5700811252666, 1424.4231086409814, 1429.9642508937359, 1085.2565992295304, 1097.2648325621853, 1098.6813823991113, 1103.707517507861, 1126.7577470843808, 6542.869142617121, 6582.539222254492, 6706.094677218611, 6775.525713200127, 6861.348171923759, 457.4282147315855, 482.94929032451086, 494.2882249560621, 506.7846165379699, 509.956653684441, 4678.836240731626, 4700.318453824177, 4780.856574302777, 4838.2016967200725, 4843.418789745253, 11931.1051671125, 12188.390580471118, 12306.07704422172, 12324.938375308066, 12341.938290308492, 11366.602168924048, 12234.510619575118, 12253.816192757116, 12254.20347677398, 12279.263244205376, 8902.332325075646, 9147.405418647766, 9157.81684366309, 9162.175404213653, 9171.58727857248, 6755.695993680198, 6855.5201135922, 6870.828229294308, 6926.203486599105, 6950.4787140965645, 10628.309159904582, 10860.239783215098, 11261.042811361127, 11276.492557835081, 11419.486123124314, 11149.832752508557, 11227.989480946753, 11228.214025018171, 11232.66302010941, 11400.201985860911, 8069.435139540175, 8408.075111737826, 8591.355648326275, 8605.95697021511, 8787.121287871161, 9612.134907858046, 9635.710928672152, 9692.0, 9715.56279967389, 9719.319647624598, 8157.8368432632205, 8178.959105204436, 8230.753077407688, 8263.091059983517, 8378.0, 8241.381896357678, 8405.07544170153, 8465.322774177928, 8503.957480212559, 8559.95720021396, 7423.072115985502, 7451.105867295742, 7469.551826890404, 7516.661750221362, 7519.962400187964, 5422.783088676409, 5642.887142257214, 5751.424857514216, 5823.213866128188, 5904.586678932434, 5640.915386269291, 5734.22587950638, 5864.501517370901, 5936.940630593812, 5993.220881285405], "value": 4977.795321341856, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.84612616574873, 358.1511816993725, 359.1094086665071, 359.960404355521, 362.06767573498246, 5717.428257174281, 5876.471117599415, 5879.618754404468, 5888.322842873065, 5919.792807251747, 5661.716914154296, 5756.769729210837, 5766.625169363989, 5797.5651826113, 5845.912311315323, 4551.704139230939, 4562.384078149441, 4576.565226303496, 4586.38083858679, 4587.655925805561, 4526.569975852289, 4535.206338890697, 4545.11370282795, 4547.840825571106, 4553.886152846172, 4475.664325175609, 4520.0, 4544.386507821451, 4567.36056952027, 4576.473705523874, 4445.88885277869, 4528.162289976349, 4535.0, 4539.296409056601, 4542.613877820356, 4000.179963107556, 4001.659029467765, 4003.099302656908, 4009.538903026131, 4025.657819085381, 5682.801101961453, 5688.914666280009, 5718.571107166979, 5735.483806457439, 5768.711564421762, 4979.601631869442, 5172.172452407634, 5261.815836445706, 5266.15741481365, 5285.894282114375, 6071.51427885768, 6078.240219972504, 6135.3864613538735, 6203.16257305267, 6207.441330280251, 4372.125574885035, 4442.666799990014, 4479.843205487825, 4506.842260520899, 4512.097580483884, 5918.970405147947, 6223.533235007348, 6279.34066922971, 6282.748690052438, 6287.937120628781, 4572.474165470947, 4572.81708731647, 4577.382053422812, 4591.334256532784, 4600.95399046012, 4603.58567728906, 4644.907101857944, 4660.207764680013, 4667.649926255476, 4677.625789936865, 5156.92264616031, 5207.151461231284, 5258.316418865523, 5260.815871444558, 5270.471563246692, 6058.787942421994, 6065.75736970525, 6082.300535438393, 6097.451229389376, 6159.414855588801, 1593.6812637472551, 1608.6702226043633, 1615.709172348965, 1618.1989914992155, 1620.8541231289005, 1011.4689787861431, 1052.1582733812918, 1063.6383629565985, 1067.514281002142, 1079.80023695615, 5897.380775018647, 5910.143029260819, 5911.231539899872, 5926.970365148147, 5945.40545945402, 6144.385561443909, 6179.536534759911, 6228.844278892973, 6237.719302631393, 6243.625382477066, 5693.68684722336, 5852.239208902901, 5898.292204935436, 5908.291005079418, 5928.517870532367, 3312.354090952241, 3351.6480769519335, 3357.2949680567353, 3362.722165577065, 3366.3267346530793, 6239.220097487814, 6360.681965901686, 6383.042543618494, 6518.348165183538, 6587.4071333580205, 6406.647634380154, 6454.19322584677, 6474.935250647433, 6486.481081513468, 6525.608463492111, 6299.905501417391, 6523.347665233438, 6539.967300163564, 6547.508936829757, 6630.171228596426, 6297.756655322421, 6334.651594162366, 6337.524685648687, 6343.9365606343345, 6373.776917807854, 6094.238220222473, 6262.432402714865, 6298.11826344303, 6337.271213810471, 6337.7148028337915, 6499.967500162469, 6524.0, 6527.3472652734345, 6535.869282614416, 6537.477001839747, 4405.29515277554, 4449.087936972977, 4467.285234362486, 4467.419235499365, 4498.840556532948, 6157.230346206724, 6272.5922815016875, 6317.873642527215, 6369.8726025480155, 6447.451966582706, 6120.17377654011, 6148.877022459616, 6168.629882207082, 6175.69121543912, 6177.598456100519, 5931.6441013539325, 6128.4790792783115, 6160.507159427325, 6164.537659675452, 6184.886720390193, 6124.479419249414, 6126.66303353302, 6146.907796382969, 6178.1659475971965, 6179.783707570214, 6155.630662160284, 6190.50475961931, 6226.501879849692, 6228.719707613259, 6303.905441418474, 5731.111677689798, 6017.247844019497, 6024.1265016573225, 6039.365866583946, 6144.678894037804], "value": 4692.623393169404, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [843.8101427178882, 847.9830403391933, 884.5356188001299, 890.612583526166, 925.4493576322094, 15048.17235052073, 16075.115868627236, 16233.756493652605, 16280.592985175377, 16305.83694163058, 14117.71764564708, 15009.174495402749, 15117.622059448518, 15120.319585618647, 15511.224438778045, 869.769511079566, 897.385291075612, 900.8108297257551, 902.1564836877541, 902.7246689759621, 879.1296616349831, 882.1707594860842, 884.0938038510546, 885.017630430222, 885.9468431894074, 935.854942483916, 941.9387739796891, 943.8584212368165, 945.229637845154, 945.2863088368307, 929.1034152043277, 931.0875342164649, 933.0482907434379, 933.7058826469674, 935.948522831244, 687.3264201082967, 694.3854688600575, 694.8818700820822, 696.1089805049494, 698.3121625199175, 14889.553313400615, 15327.846721532753, 15359.232038398148, 15375.923120384326, 15404.075755454709, 13849.307534623229, 14401.567952961323, 14412.991090623556, 14580.635484112876, 14754.70490590193, 12395.256284622952, 12449.626511204679, 12451.813222801575, 12588.937055314756, 12943.158694684815, 4130.855420060314, 4132.628063474272, 4169.97915010426, 4330.805113769857, 4339.262325404688, 85269.0, 85529.0, 86794.0, 86966.5651671744, 87605.56197219036, 11112.55549778016, 11177.552897884156, 11346.149038822121, 11488.94255528717, 11612.361320127306, 22443.32670019902, 22476.775232247794, 22511.099556017576, 22529.32412027642, 22553.4361640957, 9255.814883702424, 9554.856677149848, 9634.71095867111, 9827.361221520578, 10031.047050530187, 5265.921011184759, 5345.224942383333, 5356.973215133899, 5384.865378365572, 5429.972850135803, 3789.5263092113487, 3898.1034362096875, 3925.2542017016676, 3931.0, 3944.684425245973, 604.1662505742136, 620.3486339343671, 627.3318915355223, 629.8016124920726, 634.9873002539923, 720.3228964773097, 809.7527132167128, 814.4461765999177, 829.9875501867476, 846.7332790171076, 10587.7294724632, 10635.41505217205, 10783.730406739738, 10846.945765271123, 11203.663890083311, 710.928907109285, 768.9807754806175, 771.5255118102326, 797.9840403191904, 812.8130529978138, 5274.7890084396195, 5407.585557862716, 5449.836504904859, 5457.5633949283965, 5499.890002200013, 130134.0, 130256.3487182558, 130284.0, 131404.0, 135247.0, 30952.380952380827, 68159.65920170466, 92436.53781731048, 95199.0480095204, 111894.0, 55576.72211638997, 81562.59218703987, 93336.53331733434, 93443.0, 97272.0, 10481.0, 10596.20528460384, 10885.945570272099, 11104.111671066134, 11275.0, 52298.477015229364, 52672.473275266755, 53150.2027469596, 53673.463265366845, 54362.45637543574, 73757.6312118436, 74247.0, 79779.0, 80270.5986470064, 80964.59517702374, 13681.110727802661, 14565.41738330455, 15027.24863756786, 15497.225138743242, 15723.213839308217, 14722.63193420173, 15008.249587520362, 15460.356533132457, 15899.682006360039, 16547.917260413622, 14260.857391426369, 14338.784918226444, 14381.856181438052, 14515.709685806014, 14733.631659208606, 15314.540563783105, 16095.275712592727, 16409.261583228545, 16422.343106276094, 16479.670406592042, 14680.77978830297, 14832.406703731729, 15688.294026769052, 15697.450589229778, 15724.752347551805, 12617.747645046864, 12785.872141278469, 12813.039022073568, 12940.611781646565, 13258.734825303633, 12128.272303661808, 13057.738845223232, 13126.14680045794, 13160.407781649657, 13434.126781759154], "value": 8160.062342349654, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1507.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 106.0, 110.0, 112.0, 112.0, 112.0, 112.0, 110.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 118.0, 142.0, 176.0, 117.0, 151.0, 149.0, 117.0, 177.0, 116.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 164.0, 162.0, 162.0, 161.0, 163.0, 161.0, 162.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 77.0, 171.0, 76.0, 76.0, 76.0, 75.0, 171.0, 76.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 158.0, 84.0, 84.0, 83.0, 141.0, 82.0, 84.0, 144.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 121.0, 117.0, 118.0, 118.0, 118.0, 117.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 54.0, 51.0, 51.0, 52.0, 51.0, 51.0, 52.0, 52.0, 55.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 60.0, 61.0, 60.0, 60.0, 61.0, 60.0, 62.0, 61.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 74.0, 75.0, 72.0, 83.0, 74.0, 73.0, 78.0, 72.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 119.0, 118.0, 118.0, 137.0, 129.0, 142.0, 165.0, 120.0], "value": 130.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 156.0, 156.0, 153.0, 152.0, 158.0, 154.0, 150.0, 151.0, 154.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 70.0, 63.0, 66.0, 63.0, 62.0, 65.0, 62.0, 65.0], "value": 64.3, "unit": "score"}]}]} 07:20:16 INFO - Return code: 0 07:20:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:20:16 ERROR - # TBPL SUCCESS # 07:20:16 INFO - Running post-action listener: _resource_record_post_action 07:20:16 INFO - [mozharness: 2016-09-01 14:20:16.928911Z] Finished run-tests step (success) 07:20:16 INFO - Running post-run listener: _resource_record_post_run 07:20:17 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 330735616; Read time: 0; Write time: 237940 07:20:17 INFO - TinderboxPrint: CPU usage
13.2% 07:20:17 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 07:20:17 INFO - TinderboxPrint: I/O write bytes / time
330,735,616 / 237,940 07:20:17 INFO - TinderboxPrint: CPU idle
8,219.2 (86.8%) 07:20:17 INFO - TinderboxPrint: CPU user
1,197.6 (12.6%) 07:20:17 INFO - TinderboxPrint: Swap in / out
0 / 0 07:20:17 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 77180928; Read time: 0; Write time: 67876 07:20:17 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 0; Write bytes: 251121664; Read time: 0; Write time: 169796 07:20:17 INFO - Running post-run listener: _upload_blobber_files 07:20:17 INFO - Blob upload gear active. 07:20:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:20:17 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 07:20:17 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'] 07:20:17 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 07:20:17 INFO - (blobuploader) - INFO - Open directory for files ... 07:20:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:20:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:20:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:18 INFO - (blobuploader) - INFO - Done attempting. 07:20:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:20:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:20:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:24 INFO - (blobuploader) - INFO - Done attempting. 07:20:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:20:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:20:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:25 INFO - (blobuploader) - INFO - Done attempting. 07:20:25 INFO - (blobuploader) - INFO - Iteration through files over. 07:20:25 INFO - Return code: 0 07:20:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:20:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:20:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80704ef9bb45ba6a5d9a1617b4086e3df17fb0f82a5d2e808a93c434d25c42f87d3c6a05a00d80fc99e70e211d99b2d102dbd1560abdebcaa08a153fcc69465b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/143cd3b8561601d0f581cdb8484b9b49b55e9185289f61611aa6180d3812be7d09cca123c071ceeadc0c9b43268ab63bf60f2a9fab578b73716b3565bdaf9527", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/699e03546d93b380e93c7485c231a3a8ac014c820709a06b3935fe0cdb9ec3de4b34d8b5a01e17e68dc0613dc4212484ce2b633bff1e435af22ef5a3ba7c500f"} 07:20:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:20:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:20:25 INFO - Contents: 07:20:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80704ef9bb45ba6a5d9a1617b4086e3df17fb0f82a5d2e808a93c434d25c42f87d3c6a05a00d80fc99e70e211d99b2d102dbd1560abdebcaa08a153fcc69465b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/143cd3b8561601d0f581cdb8484b9b49b55e9185289f61611aa6180d3812be7d09cca123c071ceeadc0c9b43268ab63bf60f2a9fab578b73716b3565bdaf9527", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/699e03546d93b380e93c7485c231a3a8ac014c820709a06b3935fe0cdb9ec3de4b34d8b5a01e17e68dc0613dc4212484ce2b633bff1e435af22ef5a3ba7c500f"} 07:20:25 INFO - Running post-run listener: copy_logs_to_upload_dir 07:20:25 INFO - Copying logs to upload dir... 07:20:25 INFO - mkdir: /builds/slave/test/build/upload/logs 07:20:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.739350 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-01 07:20:24.931558) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 07:20:24.935088) ========= 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=923d835db2ba3686ebe126b700000192-1472734596.800451-993218060 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80704ef9bb45ba6a5d9a1617b4086e3df17fb0f82a5d2e808a93c434d25c42f87d3c6a05a00d80fc99e70e211d99b2d102dbd1560abdebcaa08a153fcc69465b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/143cd3b8561601d0f581cdb8484b9b49b55e9185289f61611aa6180d3812be7d09cca123c071ceeadc0c9b43268ab63bf60f2a9fab578b73716b3565bdaf9527", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/699e03546d93b380e93c7485c231a3a8ac014c820709a06b3935fe0cdb9ec3de4b34d8b5a01e17e68dc0613dc4212484ce2b633bff1e435af22ef5a3ba7c500f"} build_url:https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011411 build_url: 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80704ef9bb45ba6a5d9a1617b4086e3df17fb0f82a5d2e808a93c434d25c42f87d3c6a05a00d80fc99e70e211d99b2d102dbd1560abdebcaa08a153fcc69465b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/143cd3b8561601d0f581cdb8484b9b49b55e9185289f61611aa6180d3812be7d09cca123c071ceeadc0c9b43268ab63bf60f2a9fab578b73716b3565bdaf9527", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/699e03546d93b380e93c7485c231a3a8ac014c820709a06b3935fe0cdb9ec3de4b34d8b5a01e17e68dc0613dc4212484ce2b633bff1e435af22ef5a3ba7c500f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 07:20:24.960893) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 07:20:24.961262) ========= 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=923d835db2ba3686ebe126b700000192-1472734596.800451-993218060 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 07:20:25.046180) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:20:25.046908) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:20:25.047580) ========= ========= Total master_lag: 0.33 =========