builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-037 starttime: 1467281778.19 results: success (0) buildid: 20160630003326 builduid: cda61b0b50fa455a859583ba22270570 revision: 3053d997f2e9f0fc7dd3960927c92496df1d9ace ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:18.194021) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:18.194451) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:18.194819) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:18.221852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:18.222163) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-30 03:16:18-- 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% 42.7M=0s 2016-06-30 03:16:18 (42.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081633 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:18.320747) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:18.321081) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018343 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:18.377184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-30 03:16:18.377549) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3053d997f2e9f0fc7dd3960927c92496df1d9ace --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 3053d997f2e9f0fc7dd3960927c92496df1d9ace --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=56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-30 03:16:18,403 truncating revision to first 12 chars 2016-06-30 03:16:18,403 Setting DEBUG logging. 2016-06-30 03:16:18,403 attempt 1/10 2016-06-30 03:16:18,403 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3053d997f2e9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-30 03:16:19,025 unpacking tar archive at: mozilla-aurora-3053d997f2e9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.996114 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-30 03:16:19.390340) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:19.390657) ========= script_repo_revision: 3053d997f2e9f0fc7dd3960927c92496df1d9ace ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:19.391058) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:19.391344) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 03:16:19.399892) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-06-30 03:16:19.400313) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:16:19 INFO - MultiFileLogger online at 20160630 03:16:19 in /builds/slave/test 03:16:19 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 03:16:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:16:19 INFO - {'append_to_log': False, 03:16:19 INFO - 'base_work_dir': '/builds/slave/test', 03:16:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:16:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:16:19 INFO - 'branch': 'Mozilla-Aurora', 03:16:19 INFO - 'buildbot_json_path': 'buildprops.json', 03:16:19 INFO - 'config_files': ('talos/linux_config.py',), 03:16:19 INFO - 'default_actions': ('clobber', 03:16:19 INFO - 'read-buildbot-config', 03:16:19 INFO - 'download-and-extract', 03:16:19 INFO - 'populate-webroot', 03:16:19 INFO - 'create-virtualenv', 03:16:19 INFO - 'install', 03:16:19 INFO - 'run-tests'), 03:16:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:16:19 INFO - 'download_minidump_stackwalk': True, 03:16:19 INFO - 'download_symbols': 'ondemand', 03:16:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:16:19 INFO - 'tooltool.py': '/tools/tooltool.py', 03:16:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:16:19 INFO - '/tools/misc-python/virtualenv.py')}, 03:16:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:16:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:16:19 INFO - 'installer_path': 'installer.exe', 03:16:19 INFO - 'log_level': 'info', 03:16:19 INFO - 'log_name': 'talos', 03:16:19 INFO - 'log_to_console': True, 03:16:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:16:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:16:19 INFO - 'opt_config_files': (), 03:16:19 INFO - 'pip_index': False, 03:16:19 INFO - 'sps_profile': False, 03:16:19 INFO - 'sps_profile_interval': 0, 03:16:19 INFO - 'suite': 'dromaeojs-e10s', 03:16:19 INFO - 'system_bits': '32', 03:16:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:16:19 INFO - 'title': 'talos-linux64-ix-037', 03:16:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:16:19 INFO - 'use_talos_json': True, 03:16:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:16:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:16:19 INFO - 'work_dir': 'build'} 03:16:19 INFO - ##### 03:16:19 INFO - ##### Running clobber step. 03:16:19 INFO - ##### 03:16:19 INFO - Running pre-action listener: _resource_record_pre_action 03:16:19 INFO - Running main action method: clobber 03:16:19 INFO - rmtree: /builds/slave/test/build 03:16:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:16:20 INFO - Running post-action listener: _resource_record_post_action 03:16:20 INFO - ##### 03:16:20 INFO - ##### Finished clobber step (success) 03:16:20 INFO - ##### 03:16:20 INFO - ##### 03:16:20 INFO - ##### Running read-buildbot-config step. 03:16:20 INFO - ##### 03:16:20 INFO - Running pre-action listener: _resource_record_pre_action 03:16:20 INFO - Running main action method: read_buildbot_config 03:16:20 INFO - Using buildbot properties: 03:16:20 INFO - { 03:16:20 INFO - "project": "", 03:16:20 INFO - "product": "firefox", 03:16:20 INFO - "script_repo_revision": "production", 03:16:20 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 03:16:20 INFO - "repository": "", 03:16:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 03:16:20 INFO - "buildid": "20160630003326", 03:16:20 INFO - "pgo_build": "True", 03:16:20 INFO - "basedir": "/builds/slave/test", 03:16:20 INFO - "buildnumber": 375, 03:16:20 INFO - "slavename": "talos-linux64-ix-037", 03:16:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:16:20 INFO - "platform": "ubuntu64_hw", 03:16:20 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:16:20 INFO - "branch": "mozilla-aurora", 03:16:20 INFO - "repo_path": "releases/mozilla-aurora", 03:16:20 INFO - "revision": "3053d997f2e9f0fc7dd3960927c92496df1d9ace", 03:16:20 INFO - "stage_platform": "linux64-pgo", 03:16:20 INFO - "builduid": "cda61b0b50fa455a859583ba22270570", 03:16:20 INFO - "slavebuilddir": "test" 03:16:20 INFO - } 03:16:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:16:20 INFO - Running post-action listener: _resource_record_post_action 03:16:20 INFO - ##### 03:16:20 INFO - ##### Finished read-buildbot-config step (success) 03:16:20 INFO - ##### 03:16:20 INFO - ##### 03:16:20 INFO - ##### Running download-and-extract step. 03:16:20 INFO - ##### 03:16:20 INFO - Running pre-action listener: _resource_record_pre_action 03:16:20 INFO - Running main action method: download_and_extract 03:16:20 INFO - mkdir: /builds/slave/test/build/tests 03:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:20 INFO - https://queue.taskcluster.net/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:16:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:16:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:16:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:16:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:16:21 INFO - Downloaded 1361 bytes. 03:16:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:16:21 INFO - Using the following test package requirements: 03:16:21 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:16:21 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:21 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:16:21 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:21 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:16:21 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:21 INFO - u'jsshell-linux-x86_64.zip'], 03:16:21 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:21 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:16:21 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:16:21 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:21 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:16:21 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:21 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:16:21 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:21 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:16:21 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:16:21 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:16:21 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:16: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')]} 03:16:21 INFO - https://queue.taskcluster.net/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:16:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:16:22 INFO - Downloaded 24525237 bytes. 03:16:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:16:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:16:24 INFO - Return code: 0 03:16:24 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:16: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')]} 03:16:24 INFO - https://queue.taskcluster.net/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:16:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:16:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:16:29 INFO - Downloaded 24525237 bytes. 03:16:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:16:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:16:31 INFO - Return code: 0 03:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:31 INFO - https://queue.taskcluster.net/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:16:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:16:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:16:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:16:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:16:32 INFO - Downloaded 10833369 bytes. 03:16:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:16:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:16:32 INFO - Return code: 0 03:16: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')]} 03:16:32 INFO - https://queue.taskcluster.net/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:16:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:16:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:16:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:16:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:16:34 INFO - Downloaded 63602465 bytes. 03:16:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:16:34 INFO - mkdir: /builds/slave/test/properties 03:16:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:16:34 INFO - Writing to file /builds/slave/test/properties/build_url 03:16:34 INFO - Contents: 03:16:34 INFO - build_url:https://queue.taskcluster.net/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:16:34 INFO - Running post-action listener: _resource_record_post_action 03:16:34 INFO - Running post-action listener: set_extra_try_arguments 03:16:34 INFO - ##### 03:16:34 INFO - ##### Finished download-and-extract step (success) 03:16:34 INFO - ##### 03:16:34 INFO - ##### 03:16:34 INFO - ##### Running populate-webroot step. 03:16:34 INFO - ##### 03:16:34 INFO - Running pre-action listener: _resource_record_pre_action 03:16:34 INFO - Running main action method: populate_webroot 03:16:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:16:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:16:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:16:34 INFO - '--tppagecycles', 03:16:34 INFO - '7'], 03:16:34 INFO - 'tests': ['tsvgm']}}, 03:16:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:16:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:16:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:16:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:16:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:16:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:16:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:34 INFO - 'talos_options': ['--disable-e10s'], 03:16:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:16:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:34 INFO - 'talos_options': ['--disable-e10s'], 03:16:34 INFO - 'tests': ['damp', 'tps']}, 03:16:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:34 INFO - 'tests': ['damp', 'tps']}, 03:16:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:16:34 INFO - 'tests': ['dromaeo_dom']}, 03:16:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:16:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:16:34 INFO - 'tests': ['a11yr', 03:16:34 INFO - 'ts_paint', 03:16:34 INFO - 'tpaint', 03:16:34 INFO - 'sessionrestore', 03:16:34 INFO - 'sessionrestore_no_auto_restore', 03:16:34 INFO - 'tabpaint']}, 03:16:34 INFO - 'other-e10s': {'tests': ['a11yr', 03:16:34 INFO - 'ts_paint', 03:16:34 INFO - 'tpaint', 03:16:34 INFO - 'sessionrestore', 03:16:34 INFO - 'sessionrestore_no_auto_restore', 03:16:34 INFO - 'tabpaint']}, 03:16:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:16:34 INFO - 'ts_paint', 03:16:34 INFO - 'tpaint', 03:16:34 INFO - 'sessionrestore', 03:16:34 INFO - 'sessionrestore_no_auto_restore', 03:16:34 INFO - 'tabpaint']}, 03:16:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:16:34 INFO - 'ts_paint', 03:16:34 INFO - 'tpaint', 03:16:34 INFO - 'sessionrestore', 03:16:34 INFO - 'sessionrestore_no_auto_restore', 03:16:34 INFO - 'tabpaint']}, 03:16:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:16:34 INFO - 'tests': ['a11yr', 03:16:34 INFO - 'ts_paint', 03:16:34 INFO - 'tpaint', 03:16:34 INFO - 'sessionrestore', 03:16:34 INFO - 'sessionrestore_no_auto_restore', 03:16:34 INFO - 'tabpaint']}, 03:16:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:16:34 INFO - 'tests': ['a11yr', 03:16:34 INFO - 'ts_paint', 03:16:34 INFO - 'tpaint', 03:16:34 INFO - 'sessionrestore', 03:16:34 INFO - 'sessionrestore_no_auto_restore', 03:16:34 INFO - 'tabpaint']}, 03:16:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:16:34 INFO - 'tests': ['tsvgx', 03:16:34 INFO - 'tsvgr_opacity', 03:16:34 INFO - 'tart', 03:16:34 INFO - 'tscrollx', 03:16:34 INFO - 'cart']}, 03:16:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:16:34 INFO - 'tsvgr_opacity', 03:16:34 INFO - 'tart', 03:16:34 INFO - 'tscrollx', 03:16:34 INFO - 'cart']}, 03:16:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:34 INFO - 'talos_options': ['--disable-e10s'], 03:16:34 INFO - 'tests': ['tp5o']}, 03:16:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:34 INFO - 'tests': ['tp5o']}, 03:16:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:34 INFO - 'talos_options': ['--disable-e10s', 03:16:34 INFO - '--xperf_path', 03:16:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:34 INFO - 'tests': ['tp5n']}, 03:16:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:16:34 INFO - 'talos_options': ['--xperf_path', 03:16:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:16:34 INFO - 'tests': ['tp5n']}}, 03:16:34 INFO - 'talos.zip': {'path': '', 03:16:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:16:34 INFO - Running post-action listener: _resource_record_post_action 03:16:34 INFO - ##### 03:16:34 INFO - ##### Finished populate-webroot step (success) 03:16:34 INFO - ##### 03:16:34 INFO - ##### 03:16:34 INFO - ##### Running create-virtualenv step. 03:16:34 INFO - ##### 03:16:34 INFO - Running pre-action listener: _resource_record_pre_action 03:16:34 INFO - Running main action method: create_virtualenv 03:16:34 INFO - Creating virtualenv /builds/slave/test/build/venv 03:16:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:16:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:16:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:16:34 INFO - Using real prefix '/usr' 03:16:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:16:35 INFO - Installing distribute.............................................................................................................................................................................................done. 03:16:37 INFO - Installing pip.................done. 03:16:37 INFO - Return code: 0 03:16:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f492b11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:16:37 INFO - Using env: {'DISPLAY': ':0', 03:16:37 INFO - 'HOME': '/home/cltbld', 03:16:37 INFO - 'LANG': 'en_US.UTF-8', 03:16:37 INFO - 'LANGUAGE': 'en_US:en', 03:16:37 INFO - 'LOGNAME': 'cltbld', 03:16:37 INFO - 'MAIL': '/var/mail/cltbld', 03:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:37 INFO - 'MOZ_NO_REMOTE': '1', 03:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:37 INFO - 'NO_EM_RESTART': '1', 03:16:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:37 INFO - 'PWD': '/builds/slave/test', 03:16:37 INFO - 'SHELL': '/bin/bash', 03:16:37 INFO - 'SHLVL': '1', 03:16:37 INFO - 'TERM': 'linux', 03:16:37 INFO - 'TMOUT': '86400', 03:16:37 INFO - 'USER': 'cltbld', 03:16:37 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:37 INFO - '_': '/tools/buildbot/bin/python'} 03:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:16:37 INFO - Cleaning up... 03:16:37 INFO - Return code: 0 03:16:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f492b11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:16:37 INFO - Using env: {'DISPLAY': ':0', 03:16:37 INFO - 'HOME': '/home/cltbld', 03:16:37 INFO - 'LANG': 'en_US.UTF-8', 03:16:37 INFO - 'LANGUAGE': 'en_US:en', 03:16:37 INFO - 'LOGNAME': 'cltbld', 03:16:37 INFO - 'MAIL': '/var/mail/cltbld', 03:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:37 INFO - 'MOZ_NO_REMOTE': '1', 03:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:37 INFO - 'NO_EM_RESTART': '1', 03:16:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:37 INFO - 'PWD': '/builds/slave/test', 03:16:37 INFO - 'SHELL': '/bin/bash', 03:16:37 INFO - 'SHLVL': '1', 03:16:37 INFO - 'TERM': 'linux', 03:16:37 INFO - 'TMOUT': '86400', 03:16:37 INFO - 'USER': 'cltbld', 03:16:37 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:37 INFO - '_': '/tools/buildbot/bin/python'} 03:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:37 INFO - Downloading/unpacking psutil>=3.1.1 03:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:16:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:39 INFO - Installing collected packages: psutil 03:16:39 INFO - Running setup.py install for psutil 03:16:39 INFO - building 'psutil._psutil_linux' extension 03:16:39 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:16:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:16:40 INFO - building 'psutil._psutil_posix' extension 03:16:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:16:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:16:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:40 INFO - Successfully installed psutil 03:16:40 INFO - Cleaning up... 03:16:40 INFO - Return code: 0 03:16:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:16:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:40 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 0x7f492b11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:16:40 INFO - Using env: {'DISPLAY': ':0', 03:16:40 INFO - 'HOME': '/home/cltbld', 03:16:40 INFO - 'LANG': 'en_US.UTF-8', 03:16:40 INFO - 'LANGUAGE': 'en_US:en', 03:16:40 INFO - 'LOGNAME': 'cltbld', 03:16:40 INFO - 'MAIL': '/var/mail/cltbld', 03:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:40 INFO - 'MOZ_NO_REMOTE': '1', 03:16:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:40 INFO - 'NO_EM_RESTART': '1', 03:16:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:40 INFO - 'PWD': '/builds/slave/test', 03:16:40 INFO - 'SHELL': '/bin/bash', 03:16:40 INFO - 'SHLVL': '1', 03:16:40 INFO - 'TERM': 'linux', 03:16:40 INFO - 'TMOUT': '86400', 03:16:40 INFO - 'USER': 'cltbld', 03:16:40 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:40 INFO - '_': '/tools/buildbot/bin/python'} 03:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:16:42 INFO - Installing collected packages: mozsystemmonitor 03:16:42 INFO - Running setup.py install for mozsystemmonitor 03:16:42 INFO - Successfully installed mozsystemmonitor 03:16:42 INFO - Cleaning up... 03:16:42 INFO - Return code: 0 03:16:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:42 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 0x7f492b11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:16:42 INFO - Using env: {'DISPLAY': ':0', 03:16:42 INFO - 'HOME': '/home/cltbld', 03:16:42 INFO - 'LANG': 'en_US.UTF-8', 03:16:42 INFO - 'LANGUAGE': 'en_US:en', 03:16:42 INFO - 'LOGNAME': 'cltbld', 03:16:42 INFO - 'MAIL': '/var/mail/cltbld', 03:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:42 INFO - 'MOZ_NO_REMOTE': '1', 03:16:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:42 INFO - 'NO_EM_RESTART': '1', 03:16:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:42 INFO - 'PWD': '/builds/slave/test', 03:16:42 INFO - 'SHELL': '/bin/bash', 03:16:42 INFO - 'SHLVL': '1', 03:16:42 INFO - 'TERM': 'linux', 03:16:42 INFO - 'TMOUT': '86400', 03:16:42 INFO - 'USER': 'cltbld', 03:16:42 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:42 INFO - '_': '/tools/buildbot/bin/python'} 03:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:42 INFO - Downloading/unpacking jsonschema==2.5.1 03:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:16:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:44 INFO - Downloading functools32-3.2.3-2.tar.gz 03:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:16:45 INFO - warning: no files found matching '*.txt' 03:16:45 INFO - no previously-included directories found matching 'build' 03:16:45 INFO - no previously-included directories found matching 'dist' 03:16:45 INFO - no previously-included directories found matching '.git*' 03:16:45 INFO - Installing collected packages: jsonschema, functools32 03:16:45 INFO - Running setup.py install for functools32 03:16:45 INFO - warning: no files found matching '*.txt' 03:16:45 INFO - no previously-included directories found matching 'build' 03:16:45 INFO - no previously-included directories found matching 'dist' 03:16:45 INFO - no previously-included directories found matching '.git*' 03:16:45 INFO - Successfully installed jsonschema functools32 03:16:45 INFO - Cleaning up... 03:16:45 INFO - Return code: 0 03:16:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:45 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 0x7f492b11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:16:45 INFO - Using env: {'DISPLAY': ':0', 03:16:45 INFO - 'HOME': '/home/cltbld', 03:16:45 INFO - 'LANG': 'en_US.UTF-8', 03:16:45 INFO - 'LANGUAGE': 'en_US:en', 03:16:45 INFO - 'LOGNAME': 'cltbld', 03:16:45 INFO - 'MAIL': '/var/mail/cltbld', 03:16:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:45 INFO - 'MOZ_NO_REMOTE': '1', 03:16:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:45 INFO - 'NO_EM_RESTART': '1', 03:16:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:45 INFO - 'PWD': '/builds/slave/test', 03:16:45 INFO - 'SHELL': '/bin/bash', 03:16:45 INFO - 'SHLVL': '1', 03:16:45 INFO - 'TERM': 'linux', 03:16:45 INFO - 'TMOUT': '86400', 03:16:45 INFO - 'USER': 'cltbld', 03:16:45 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:16:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:45 INFO - '_': '/tools/buildbot/bin/python'} 03:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:45 INFO - Downloading/unpacking blobuploader==1.2.4 03:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:47 INFO - Downloading blobuploader-1.2.4.tar.gz 03:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:16:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:16:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:48 INFO - Downloading docopt-0.6.1.tar.gz 03:16:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:16:48 INFO - Installing collected packages: blobuploader, requests, docopt 03:16:48 INFO - Running setup.py install for blobuploader 03:16:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:16:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:16:48 INFO - Running setup.py install for requests 03:16:48 INFO - Running setup.py install for docopt 03:16:48 INFO - Successfully installed blobuploader requests docopt 03:16:48 INFO - Cleaning up... 03:16:48 INFO - Return code: 0 03:16:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:48 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 0x7f492b11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:16:48 INFO - Using env: {'DISPLAY': ':0', 03:16:48 INFO - 'HOME': '/home/cltbld', 03:16:48 INFO - 'LANG': 'en_US.UTF-8', 03:16:48 INFO - 'LANGUAGE': 'en_US:en', 03:16:48 INFO - 'LOGNAME': 'cltbld', 03:16:48 INFO - 'MAIL': '/var/mail/cltbld', 03:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:48 INFO - 'MOZ_NO_REMOTE': '1', 03:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:48 INFO - 'NO_EM_RESTART': '1', 03:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:48 INFO - 'PWD': '/builds/slave/test', 03:16:48 INFO - 'SHELL': '/bin/bash', 03:16:48 INFO - 'SHLVL': '1', 03:16:48 INFO - 'TERM': 'linux', 03:16:48 INFO - 'TMOUT': '86400', 03:16:48 INFO - 'USER': 'cltbld', 03:16:48 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:48 INFO - '_': '/tools/buildbot/bin/python'} 03:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:16:48 INFO - Running setup.py (path:/tmp/pip-I8Yozo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:16:48 INFO - Running setup.py (path:/tmp/pip-SiiC0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:16:49 INFO - Running setup.py (path:/tmp/pip-VADKVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:16:49 INFO - Running setup.py (path:/tmp/pip-gMIT33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:16:49 INFO - Running setup.py (path:/tmp/pip-hWJiYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:16:49 INFO - Running setup.py (path:/tmp/pip-sgtfdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:16:49 INFO - Running setup.py (path:/tmp/pip-TkopQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:16:49 INFO - Running setup.py (path:/tmp/pip-WqwJsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:16:49 INFO - Running setup.py (path:/tmp/pip-krigJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:16:49 INFO - Running setup.py (path:/tmp/pip-mUD1Cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:16:49 INFO - Running setup.py (path:/tmp/pip-qAGwuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:16:49 INFO - Running setup.py (path:/tmp/pip-gUHnMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:16:49 INFO - Running setup.py (path:/tmp/pip-kWxcfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:16:49 INFO - Running setup.py (path:/tmp/pip-65fIij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:49 INFO - Running setup.py (path:/tmp/pip-YYNDis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:16:49 INFO - Running setup.py (path:/tmp/pip-brXXv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:16:50 INFO - Running setup.py (path:/tmp/pip-OT0bJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:16:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:16:50 INFO - Running setup.py install for manifestparser 03:16:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Running setup.py install for mozcrash 03:16:50 INFO - Running setup.py install for mozdebug 03:16:50 INFO - Running setup.py install for mozdevice 03:16:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Running setup.py install for mozfile 03:16:50 INFO - Running setup.py install for mozhttpd 03:16:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Running setup.py install for mozinfo 03:16:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Running setup.py install for mozInstall 03:16:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:16:50 INFO - Running setup.py install for mozleak 03:16:51 INFO - Running setup.py install for mozlog 03:16:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:16:51 INFO - Running setup.py install for moznetwork 03:16:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:16:51 INFO - Running setup.py install for mozprocess 03:16:51 INFO - Running setup.py install for mozprofile 03:16:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:16:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:16:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:16:51 INFO - Running setup.py install for mozrunner 03:16:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:16:51 INFO - Running setup.py install for mozscreenshot 03:16:51 INFO - Running setup.py install for moztest 03:16:51 INFO - Running setup.py install for mozversion 03:16:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:16:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:16:51 INFO - Cleaning up... 03:16:51 INFO - Return code: 0 03:16:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:51 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 0x7f492b11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:16:51 INFO - Using env: {'DISPLAY': ':0', 03:16:51 INFO - 'HOME': '/home/cltbld', 03:16:51 INFO - 'LANG': 'en_US.UTF-8', 03:16:51 INFO - 'LANGUAGE': 'en_US:en', 03:16:51 INFO - 'LOGNAME': 'cltbld', 03:16:51 INFO - 'MAIL': '/var/mail/cltbld', 03:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:51 INFO - 'MOZ_NO_REMOTE': '1', 03:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:51 INFO - 'NO_EM_RESTART': '1', 03:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:51 INFO - 'PWD': '/builds/slave/test', 03:16:51 INFO - 'SHELL': '/bin/bash', 03:16:51 INFO - 'SHLVL': '1', 03:16:51 INFO - 'TERM': 'linux', 03:16:51 INFO - 'TMOUT': '86400', 03:16:51 INFO - 'USER': 'cltbld', 03:16:51 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:51 INFO - '_': '/tools/buildbot/bin/python'} 03:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:16:52 INFO - Running setup.py (path:/tmp/pip-wvbwLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:16:52 INFO - Running setup.py (path:/tmp/pip-tIs7eT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:16:52 INFO - Running setup.py (path:/tmp/pip-ejMYCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:16:52 INFO - Running setup.py (path:/tmp/pip-UxdmwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:16:52 INFO - Running setup.py (path:/tmp/pip-LHEDsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:16:52 INFO - Running setup.py (path:/tmp/pip-9wBVGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:16:52 INFO - Running setup.py (path:/tmp/pip-AEOkxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:16:52 INFO - Running setup.py (path:/tmp/pip-S7e4ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:16:52 INFO - Running setup.py (path:/tmp/pip-SK6pWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:16:52 INFO - Running setup.py (path:/tmp/pip-hX_8Ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:16:52 INFO - Running setup.py (path:/tmp/pip-Tgoqzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:16:52 INFO - Running setup.py (path:/tmp/pip-tiqgrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:16:53 INFO - Running setup.py (path:/tmp/pip-ULTX0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:16:53 INFO - Running setup.py (path:/tmp/pip-6NYOk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:53 INFO - Running setup.py (path:/tmp/pip-v20UHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:16:53 INFO - Running setup.py (path:/tmp/pip-oOftbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:16:53 INFO - Running setup.py (path:/tmp/pip-uLnQRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:16:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:55 INFO - Downloading blessings-1.6.tar.gz 03:16:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:16:55 INFO - Installing collected packages: blessings 03:16:55 INFO - Running setup.py install for blessings 03:16:55 INFO - Successfully installed blessings 03:16:55 INFO - Cleaning up... 03:16:55 INFO - Return code: 0 03:16:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:16:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:55 INFO - Reading from file tmpfile_stdout 03:16:55 INFO - Current package versions: 03:16:55 INFO - argparse == 1.2.1 03:16:55 INFO - blessings == 1.6 03:16:55 INFO - blobuploader == 1.2.4 03:16:55 INFO - docopt == 0.6.1 03:16:55 INFO - functools32 == 3.2.3-2 03:16:55 INFO - jsonschema == 2.5.1 03:16:55 INFO - manifestparser == 1.1 03:16:55 INFO - mozInstall == 1.12 03:16:55 INFO - mozcrash == 0.17 03:16:55 INFO - mozdebug == 0.1 03:16:55 INFO - mozdevice == 0.48 03:16:55 INFO - mozfile == 1.2 03:16:55 INFO - mozhttpd == 0.7 03:16:55 INFO - mozinfo == 0.9 03:16:55 INFO - mozleak == 0.1 03:16:55 INFO - mozlog == 3.2 03:16:55 INFO - moznetwork == 0.27 03:16:55 INFO - mozprocess == 0.22 03:16:55 INFO - mozprofile == 0.28 03:16:55 INFO - mozrunner == 6.11 03:16:55 INFO - mozscreenshot == 0.1 03:16:55 INFO - mozsystemmonitor == 0.3 03:16:55 INFO - moztest == 0.7 03:16:55 INFO - mozversion == 1.4 03:16:55 INFO - psutil == 3.1.1 03:16:55 INFO - requests == 1.2.3 03:16:55 INFO - wsgiref == 0.1.2 03:16:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:55 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 0x7f492b11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:16:55 INFO - Using env: {'DISPLAY': ':0', 03:16:55 INFO - 'HOME': '/home/cltbld', 03:16:55 INFO - 'LANG': 'en_US.UTF-8', 03:16:55 INFO - 'LANGUAGE': 'en_US:en', 03:16:55 INFO - 'LOGNAME': 'cltbld', 03:16:55 INFO - 'MAIL': '/var/mail/cltbld', 03:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:55 INFO - 'MOZ_NO_REMOTE': '1', 03:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:55 INFO - 'NO_EM_RESTART': '1', 03:16:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:55 INFO - 'PWD': '/builds/slave/test', 03:16:55 INFO - 'SHELL': '/bin/bash', 03:16:55 INFO - 'SHLVL': '1', 03:16:55 INFO - 'TERM': 'linux', 03:16:55 INFO - 'TMOUT': '86400', 03:16:55 INFO - 'USER': 'cltbld', 03:16:55 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:55 INFO - '_': '/tools/buildbot/bin/python'} 03:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:16:55 INFO - Cleaning up... 03:16:55 INFO - Return code: 0 03:16:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:55 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 0x7f492b11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:16:55 INFO - Using env: {'DISPLAY': ':0', 03:16:55 INFO - 'HOME': '/home/cltbld', 03:16:55 INFO - 'LANG': 'en_US.UTF-8', 03:16:55 INFO - 'LANGUAGE': 'en_US:en', 03:16:55 INFO - 'LOGNAME': 'cltbld', 03:16:55 INFO - 'MAIL': '/var/mail/cltbld', 03:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:55 INFO - 'MOZ_NO_REMOTE': '1', 03:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:55 INFO - 'NO_EM_RESTART': '1', 03:16:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:55 INFO - 'PWD': '/builds/slave/test', 03:16:55 INFO - 'SHELL': '/bin/bash', 03:16:55 INFO - 'SHLVL': '1', 03:16:55 INFO - 'TERM': 'linux', 03:16:55 INFO - 'TMOUT': '86400', 03:16:55 INFO - 'USER': 'cltbld', 03:16:55 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:55 INFO - '_': '/tools/buildbot/bin/python'} 03:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:16:56 INFO - Cleaning up... 03:16:56 INFO - Return code: 0 03:16:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:56 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f492b11ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a1b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:56 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', 'flake8'] in /builds/slave/test/build 03:16:56 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 flake8 03:16:56 INFO - Using env: {'DISPLAY': ':0', 03:16:56 INFO - 'HOME': '/home/cltbld', 03:16:56 INFO - 'LANG': 'en_US.UTF-8', 03:16:56 INFO - 'LANGUAGE': 'en_US:en', 03:16:56 INFO - 'LOGNAME': 'cltbld', 03:16:56 INFO - 'MAIL': '/var/mail/cltbld', 03:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:56 INFO - 'MOZ_NO_REMOTE': '1', 03:16:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:56 INFO - 'NO_EM_RESTART': '1', 03:16:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:56 INFO - 'PWD': '/builds/slave/test', 03:16:56 INFO - 'SHELL': '/bin/bash', 03:16:56 INFO - 'SHLVL': '1', 03:16:56 INFO - 'TERM': 'linux', 03:16:56 INFO - 'TMOUT': '86400', 03:16:56 INFO - 'USER': 'cltbld', 03:16:56 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:56 INFO - '_': '/tools/buildbot/bin/python'} 03:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:56 INFO - Downloading/unpacking flake8 03:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:16:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:16:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:16:59 INFO - no previously-included directories found matching 'docs/_build' 03:16:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:16:59 INFO - Running setup.py install for pep8 03:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:16:59 INFO - no previously-included directories found matching 'docs/_build' 03:16:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:16:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:16:59 INFO - Cleaning up... 03:16:59 INFO - Return code: 0 03:16:59 INFO - Running post-action listener: _resource_record_post_action 03:16:59 INFO - Running post-action listener: _start_resource_monitoring 03:16:59 INFO - Starting resource monitoring. 03:16:59 INFO - ##### 03:16:59 INFO - ##### Finished create-virtualenv step (success) 03:16:59 INFO - ##### 03:16:59 INFO - ##### 03:16:59 INFO - ##### Running install step. 03:16:59 INFO - ##### 03:16:59 INFO - Running pre-action listener: _resource_record_pre_action 03:16:59 INFO - Running main action method: install 03:16:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:00 INFO - Reading from file tmpfile_stdout 03:17:00 INFO - Detecting whether we're running mozinstall >=1.0... 03:17:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:17:00 INFO - Reading from file tmpfile_stdout 03:17:00 INFO - Output received: 03:17:00 INFO - Usage: mozinstall [options] installer 03:17:00 INFO - Options: 03:17:00 INFO - -h, --help show this help message and exit 03:17:00 INFO - -d DEST, --destination=DEST 03:17:00 INFO - Directory to install application into. [default: 03:17:00 INFO - "/builds/slave/test"] 03:17:00 INFO - --app=APP Application being installed. [default: firefox] 03:17:00 INFO - mkdir: /builds/slave/test/build/application 03:17:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:17:16 INFO - Reading from file tmpfile_stdout 03:17:16 INFO - Output received: 03:17:16 INFO - /builds/slave/test/build/application/firefox/firefox 03:17:16 INFO - Running post-action listener: _resource_record_post_action 03:17:16 INFO - ##### 03:17:16 INFO - ##### Finished install step (success) 03:17:16 INFO - ##### 03:17:16 INFO - ##### 03:17:16 INFO - ##### Running run-tests step. 03:17:16 INFO - ##### 03:17:16 INFO - Running pre-action listener: _resource_record_pre_action 03:17:16 INFO - Running main action method: run_tests 03:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:17:16 INFO - Python 2.7.3 03:17:16 INFO - Return code: 0 03:17:16 INFO - grabbing minidump binary from tooltool 03:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:16 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 0x1aaa1c0>, '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 0x1aab0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1aab550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:17:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:17:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:17:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:17:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:17:16 INFO - Return code: 0 03:17:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:17:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:17:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:17:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:17:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:17:17 INFO - Return code: 0 03:17:17 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/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 03:17:17 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/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 03:17:17 INFO - Using env: {'DISPLAY': ':0', 03:17:17 INFO - 'HOME': '/home/cltbld', 03:17:17 INFO - 'LANG': 'en_US.UTF-8', 03:17:17 INFO - 'LANGUAGE': 'en_US:en', 03:17:17 INFO - 'LOGNAME': 'cltbld', 03:17:17 INFO - 'MAIL': '/var/mail/cltbld', 03:17:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:17:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:17 INFO - 'MOZ_NO_REMOTE': '1', 03:17:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:17:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:17 INFO - 'NO_EM_RESTART': '1', 03:17:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:17 INFO - 'PWD': '/builds/slave/test', 03:17:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:17:17 INFO - 'SHELL': '/bin/bash', 03:17:17 INFO - 'SHLVL': '1', 03:17:17 INFO - 'TERM': 'linux', 03:17:17 INFO - 'TMOUT': '86400', 03:17:17 INFO - 'USER': 'cltbld', 03:17:17 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288', 03:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:17 INFO - '_': '/tools/buildbot/bin/python'} 03:17:17 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/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 03:17:17 INFO - mozversion application_buildid: 20160630003326 03:17:17 INFO - mozversion application_changeset: 3053d997f2e9f0fc7dd3960927c92496df1d9ace 03:17:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:17:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:17:17 INFO - mozversion application_name: Firefox 03:17:17 INFO - mozversion application_remotingname: firefox-dev 03:17:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:17:17 INFO - mozversion application_vendor: Mozilla 03:17:17 INFO - mozversion application_version: 49.0a2 03:17:17 INFO - mozversion platform_buildid: 20160630003326 03:17:17 INFO - mozversion platform_changeset: 3053d997f2e9f0fc7dd3960927c92496df1d9ace 03:17:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:17:17 INFO - mozversion platform_version: 49.0a2 03:17:17 INFO - using testdate: 1467281837 03:17:17 INFO - actual date: 1467281837 03:17:17 INFO - starting webserver on 'localhost:47712' 03:17:17 INFO - SUITE-START | Running 2 tests 03:17:17 INFO - TEST-START | dromaeo_css 03:17:17 INFO - operating with platform_type : linux_ 03:17:17 INFO - Initialising browser for dromaeo_css test... 03:17:17 INFO - TEST-INFO | started process 12621 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgoba1L/profile http://localhost:47712/getInfo.html) 03:17:22 INFO - PROCESS | 12621 | __metrics Screen width/height:1600/1200 03:17:22 INFO - PROCESS | 12621 | colorDepth:24 03:17:22 INFO - PROCESS | 12621 | Browser inner width/height: 1024/697 03:17:22 INFO - PROCESS | 12621 | __metrics 03:17:23 ERROR - PROCESS | 12621 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 03:17:23 INFO - TEST-INFO | 12621: exit 0 03:17:23 INFO - Browser initialized. 03:17:23 INFO - Running cycle 1/1 for dromaeo_css test... 03:17:23 INFO - TEST-INFO | started process 12752 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgoba1L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:35 INFO - PROCESS | 12752 | RSS: Main: 156041216 03:17:35 INFO - PROCESS | 12752 | 03:18:25 INFO - PROCESS | 12752 | 1467281905210 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:20:33 INFO - PROCESS | 12752 | Cycle 1(1): loaded http://localhost:47712/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47712/tests/dromaeo/cssquery-ext.html) 03:20:34 INFO - PROCESS | 12752 | RSS: Main: 160313344 03:20:34 INFO - PROCESS | 12752 | 03:23:36 INFO - PROCESS | 12752 | Cycle 1(1): loaded http://localhost:47712/tests/dromaeo/cssquery-ext.html (next: http://localhost:47712/tests/dromaeo/cssquery-jquery.html) 03:23:37 INFO - PROCESS | 12752 | RSS: Main: 157331456 03:23:37 INFO - PROCESS | 12752 | 03:26:38 INFO - PROCESS | 12752 | Cycle 1(1): loaded http://localhost:47712/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47712/tests/dromaeo/cssquery-mootools.html) 03:26:39 INFO - PROCESS | 12752 | RSS: Main: 156966912 03:26:39 INFO - PROCESS | 12752 | 03:29:41 INFO - PROCESS | 12752 | Cycle 1(1): loaded http://localhost:47712/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47712/tests/dromaeo/cssquery-prototype.html) 03:29:41 INFO - PROCESS | 12752 | RSS: Main: 156606464 03:29:41 INFO - PROCESS | 12752 | 03:32:44 INFO - PROCESS | 12752 | Cycle 1(1): loaded http://localhost:47712/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47712/tests/dromaeo/cssquery-yui.html) 03:32:44 INFO - PROCESS | 12752 | RSS: Main: 158511104 03:32:44 INFO - PROCESS | 12752 | 03:35:47 INFO - PROCESS | 12752 | Cycle 1(1): loaded http://localhost:47712/tests/dromaeo/cssquery-yui.html (next: http://localhost:47712/tests/dromaeo/cssquery-dojo.html) 03:35:47 INFO - PROCESS | 12752 | RSS: Main: 159268864 03:35:47 INFO - PROCESS | 12752 | 03:35:47 INFO - PROCESS | 12752 | __start_tp_report 03:35:47 INFO - PROCESS | 12752 | _x_x_mozilla_page_load 03:35:47 INFO - PROCESS | 12752 | _x_x_mozilla_page_load_details 03:35:47 INFO - PROCESS | 12752 | |i|pagename|runs| 03:35:47 INFO - PROCESS | 12752 | |0;dojo.html;3365.6802603752644;3444.4833275008755;3444.896653100406;3457;3477.565304336958;5737.13942908564;5764.279465066867;5779.335376431712;5795.072788353864;5839.79560715375;5783.855403614912;5812.070068788995;5836.941630583693;5871.618344807584;5873.236479257701;5224.529792318691;5262.789488420459;5305.071612467803;5308.787648494055;5320.680759154463;5117.5650069744115;5167.4057483389515;5218.556422704075;5348.358197016363;5357.303550538444;5123.641345105844;5159.7678104485385;5182.326297581328;5185.429602743714;5193.298904647862;5195.090859099661;5246.632735708521;5259.21111833228;5265.183896496049;5285.6300058995885;5083.72039537829;5145.228215767627;5204.843854684365;5258.237555554461;5273.630845840793;5515.034868897927;5566.777328906839;5579.274694289757;5589.664620122806;5605;6121.234845644294;6141.109539116803;6179.3202747697715;6180.81457556274;6191.102290167901;6008.579399442041;6129.754809807602;6213.316535181127;6224.688765561704;6246.875562398766;5746.454086861741;5819.883602327972;5825.330087040003;5827.970860145672;5885.323187833411;6323.336049714806;6325.272593651789;6356.364363563699;6365.745370185141;6370.0763389308295;5884.382139875252;5968.045112782021;5990.850228744316;6050.2437195350585;6106.78626248088;6186.566940314135;6190.226221722285;6204.937950620436;6207.937920620826;6222.346653601307;6096.8780624388155;6112.663803490851;6161.661108639068;6165.938340616536;6196.256449226122;6040.697965101727;6090.725917333731;6133.82435864808;6141.884937092159;6171.197744293213;4713.95286047142;4738.620910327166;4740.19416699162;4755.95244047562;4757.785899634525;4217.388478670576;4233.407322974786;4244.957550424518;4255.5744425557195;4263.381809637647;5739.512141468022;5857.7656893724625;5925.288965324103;5938.792142275;5959.344472108065;6130.1724267224245;6149.938500615026;6163.876722465526;6188.907166392508;6263.342349053375;5749.712514374348;5900.586958912921;5902.2327097477055;5910.408959104056;5923.881522369615;5287.709175995357;5315.495027972336;5325.2810870531985;5356.892862142813;5365.141577347606;6398.584092034003;6458.192725909262;6487.3836985486305;6494.577852439576;6514.990176522647;6119.602225855305;6147.262328520606;6275.3724627536885;6281.874362512815;6288.434040936155;6404.71178796946;6506;6513.73945042193;6519.185101862267;6546.214454265519;6161.938380616316;6165.445109940127;6184.226971628546;6208.627482351073;6234.096056071753;6139.693015349126;6169.907451388322;6219.40915613016;6316.021016742412;6342.587731797418;6071.119687645355;6117.571769976059;6121;6156.045812899008;6174.073888916699;6085.087236914498;6106.6336019838955;6144.600600960924;6160.3223645398075;6166.876662466815;5823.301203855564;5937.851553711192;5939.703014849154;5986.102084687332;6063.120847477003;6062.151298818258;6124.846878828065;6161.876762464815;6201.937980620317;6203.2556093269095;6032.8793424132145;6040.728167232573;6046.2139921809885;6050.515958723379;6052.243469566304 03:35:47 INFO - PROCESS | 12752 | |1;ext.html;603.471962033221;668.5086461450829;670.829402692302;672.3008071605531;678.8302924268933;42648.36027459591;46813.999999999956;46969;47036.52963470373;47322.763386183105;46707.299390509084;47203.29195062077;47272;47288.0542389152;47382.52617473824;8981.146791054856;9049.819003619923;9089.772755681095;9140.405873618227;9154.130357616015;8931.73204803857;9015.188633023026;9049.728508144766;9074.410163339395;9089.727308180733;9121.771955701095;9121.817563648723;9133.543322833864;9136.817263654722;9169.03725108864;9112.544372781334;9124.0419755926;9125.908740912606;9132.269418446542;9154.359194856363;9002.324825638103;9109;9111.453312801254;9114;9156.816863662689;43623;43639.78180109111;43660.56339436597;44011.11977760459;44058;3805.296020236265;4113.136241389312;4150.771707556083;4263.253930562139;4456.8885777855485;34454.483182752025;34908.6509134908;35274.470882936774;35294.294114117576;35442.8227858859;4539.245247403271;4589.472210695746;4608.585227329557;4706.811727530895;4708.905821883577;382885;384936;388175;388193;388781.99999999715;68014.65992670105;68448.31551684519;68524.6573767128;68652.65673671699;68863.3113668857;39566;40585.1882962345;40714.79642601827;42877.57122428798;43177;16229.188540572924;16367.42714005004;16418;16433.83566164347;16481.34074637025;12665.240085594894;12731.10882238253;12738.172018818746;12844.807327890088;12849.293288869016;21881.67177492339;22222.777772222395;22277.66583501249;22326.21858234954;22376.32871013872;7812.296893279632;7952.522848629101;8042.477238979448;8051.557164356017;8139;983.5328219095873;1079.1582565598846;1090.3022065877844;1091.050785816329;1175.1538891997918;33206.83396583002;33360.83319583387;33441.49837752436;33943.32113357719;34533.48199777005;1132.5356603792407;1206.897413719826;1217.1236709569273;1228.7173949991552;1244.7261602447447;18033.819661803478;18184.545386365447;18190.818091819176;18334.81665183331;18344.266229350676;561347;583235;591208;592063;886569;66285.33714662888;66886.33113668898;66953;67409.66295168591;67657.32342676609;1456.533909149062;1503.7293287208188;1512.3648067811546;1515.8635722785002;1521.4674863797582;34057.318853623285;34523.96428107161;34819.82590087033;34994.650053499136;35173.824130879184;57877.71061144836;58878.411215887296;58882;58938;59022.40977590169;502443;509874;510711;511891;512462;31846.52230216595;31947.68052319447;31990.84004579962;32305.192370190933;32396.35207295888;54368;54439.72780136074;54849;54945;55361.44638553563;45015.324770127816;45644;45962.77018614885;46029.53970460386;46991.530084698716;44897.775511122236;45550;45698.77150614226;46865.297020545404;47204.52795472139;45090.323645146;45259.773701131286;46100.76949615365;46153.538464616264;46189.07621847477;38127.300789697925;38692.80653596714;38806;39523.40714889221;39848.402273966494;30323.241918952204;30546.541801873434;30786.846065770424;31159;31208.21979450532 03:35:47 INFO - PROCESS | 12752 | |2;jquery.html;421.0799403303781;424.1749796645522;426.22852636727515;426.94236278102454;435.8670605465332;19180.712289315656;19329.903350483244;20178;20531.794682053176;20534.281300154496;19418.320358787445;19426.708599370984;19660.901695491502;20467.692984605204;20469.692954605656;5824.446677565635;5945.940540594604;5966.67183304918;5982.282126144869;6042.184305118819;5743.597948143632;5846.123081537782;5948.375420580842;5952.494038006775;6004.54965877561;5844.795432159854;5859.267591551056;5885.911711324312;5889.617174883619;5913.645181289115;5706.343770466408;5829.12563115534;5850.824475265727;5907.7636894524385;5925.703714814242;5284.735763211824;5296.311479507678;5319.042572336978;5332.840014799563;5340.599455040849;23717.169899053624;23883.402914927094;24139.517209655885;24443.511129777482;24450.633240501407;2448.09420514409;2538.263903467992;2541.301142185895;2567.5763499022637;2605.908793192248;15352.846471535366;15557.766633500507;15595.688086238326;15723.764143537855;15794.842051579568;1993.2027189124467;2011.095007246745;2029.2796057399557;2037.327681864981;2068.720722702431;75430;76166.23833761702;77909.22090779133;83455.582722086;85326;10353.896461035443;10496.107830834311;10648.201384896009;10873.40196289196;10968.396738179477;14345.64135896611;14500;14506.057106288057;14532.491362802462;14623.268836558342;7951.846982187551;8171.10117887032;8218;8232.958835205785;8322.542260175627;7796.142424333319;7854.960725196337;7884.132745398002;8029.759107226792;8040.758777236692;4327.826886924488;4424.933625995613;4630.490646028934;4666.60333871618;4999.650024498323;2040.3470889315574;2109.398821335912;2174.184680744721;2174.6411842045886;2180.476685595477;965.8696076029646;1016.0753714120116;1039.2153923787644;1039.3504059962524;1042.166266986423;18867.05664716759;19600.607987840165;19875.403737887886;19975.60048799016;20491.28280510175;1061.0821639281965;1078.0567003871613;1087.2601045365793;1093.6718984304678;1123.152020224725;10935.671929842118;11281.379524126252;11383;11392.145589080852;11584.47869845842;469685;480782;494357;496296;497663;91750.54124729334;106149.46925265325;109041.45479272712;109794.45102774435;109953;1079.8434227037035;1098.9175811814146;1124.668222874254;1133.9149563782748;1139.7492551638793;10782.60522202868;18271.634567308844;18797.90601046986;18884.33904813325;18930.43208703741;40847.38728919009;45554.08891822079;46254.306185406575;46361.304580430646;47071.52928470672;60525.69737151286;60568.69715651393;61319;62925;63316;12766.17019893704;13272.734545308847;13488.595342139752;13697.931510342385;13760.31198440094;14503;14559.708805824037;14560.41758329655;14785.630359241106;14844.7773283403;12586.18189817659;12965;13019.218846869218;13269.274994250685;13286.202827829975;12418.37908104573;12558.623241302776;12776.6805829855;12781.297028663514;13027.413766380347;12767.425465853874;13022.348882555645;13063.934680326538;13240.271785051915;13584.592462226148;13528.39122239482;13578;13627.454901803816;13755.243461609307;13795.79306310386;12602.180858244184;12768.872311276768;12971.5459958901;12972.805407919077;13010.869891301345 03:35:47 INFO - PROCESS | 12752 | |3;mootools.html;1586.539903428006;1599.360255897641;1815.972760408594;1921.8846869187842;1948.658984677681;8381.91618083819;8949.28405727542;9090.090990900906;9615.134637882598;9693.903060969387;9074.818503629924;9215.308851836106;9359.110884465965;9379.812403751921;9388.718338449857;2387.701537307832;2438.6463962725393;2442.572549803786;2444.5966415541416;2446.2905757330354;2346.3195673254736;2370.2178281167257;2384.6899903012586;2388.486475407787;2395.209580838319;2374.394529395002;2389.545986262613;2407.590709579367;2417.468157005465;2427.3446169534213;2363.869987150706;2383.8092952563925;2385.85684858909;2389.4026493376655;2418.5525677749583;2384.4158181245534;2396.1733202045266;2411.4333131714034;2420.612701967694;2433.0267892842826;9154.176124148791;9294.256459483226;9315.860262096074;9368.95315523418;9433.103855133751;5379.731013449351;5466.699331536764;5473.890522189574;5493.587980901449;5498.037843377433;3448.137965508623;3456.084137703511;3514.1741690702665;3547.8935631931085;3555;1121.2991880074953;1130.3556972525664;1137.9032782213521;1154.2093665838843;1158.3108050709898;12838.358082095858;12851.35743212845;12896.290704011275;12906.290154041524;12937.48250069996;4773.498782627844;4785.976070119627;4886.462489126193;4915.459299477055;4939.802407903644;10751.78496430067;10754.462276886125;10772.569097236023;10776.461176941279;10791.352518848895;5448.727563621803;5463.945360546423;5467.1799230115;5478.205660179252;5491.505764481136;4548.840790572314;4882.804687812519;4954.430240522314;5137.820176293886;5210.687358758487;4203;4233.238017156879;4239.533651298355;4263.616274535245;4275.358696195595;1259.1563652352945;1262.6275248801442;1270.8220849081135;1323.0672375974914;1325.1982550556968;961.3943215774018;1022.6625213679467;1034.4517405774952;1049.0086867909904;1050.9316894401838;6125.724342404603;6192.349803270592;6222.066689996446;6227.532935029801;6322.178116844781;496.48861672477403;502.6607040247818;512.4363200479443;517;517.0408891506277;4203.348480985452;4244.427002354642;4251.511076226274;4254.468191476066;4275.444192254987;11232.606858760068;11440.485178167002;11537.423128843524;11592.826107608393;11612.6516204514;11473.368964706855;11506.137039722054;11530.88469115315;11570.884291157148;11573.363465009505;8506.191911768248;8571.95714021426;8666.913330866737;8668.219860212484;8683.56582170889;5940.287165540163;6008.699565021644;6026.578139530367;6080.8783824324155;6084.665343406155;10506.737331566772;10679.359238445719;10749;10750.892491074988;10765.946170269099;10674.35953842772;10682.412467314372;10938;10959.06847917936;10962;7611.885821712568;8021.8395632088195;8228.25945664893;8234.588270586328;8303.377246706643;9179.724608261762;9223.677171298972;9251.76870578214;9278.11857873501;9333.626654933998;8175.509469431853;8311.792205194677;8319.584020799057;8344.666213351398;8480.957595211985;8009.959950200212;8057.637406316612;8171.42800003994;8179.182081791773;8188.2630563249595;7295.489315747846;7296.635168241461;7333.083364579427;7348.522346047704;7391.223921488381;5340.038793017294;5355.518003379714;5372.435894231049;5424.6269697392145;5434.891302174014;5322.09524380864;5366.892662146814;5417.079096553674;5445.809396671098;5452.7546260417585 03:35:47 INFO - PROCESS | 12752 | |4;prototype.html;340.65423595051027;347.4319487637712;352.0564886105237;358.102952104977;362.7841434346563;5743.138529220619;5774.65352078875;5848.766049358021;5855.970720146393;5872.882542349151;5646.774129034834;5705.77176912923;5741.224934633823;5768.567357448187;5777.82666520004;4239.6396306314;4532.002959348946;4533.750643714594;4543.0459603483305;4543.273076307792;4384.473863136428;4390.231709450849;4390.692651514395;4404.731563244932;4416.668749843759;4341.2836881914445;4365.585269399402;4369.257226271541;4370.235208838457;4378.1462614790125;4389.824407023715;4408.647308215351;4414.646828253732;4415.293553031532;4427.203103441379;3792.924141517182;3964.9008774780573;3965.7025723070597;3966.2662407454714;3978.204359128186;5569.164625306236;5573.554115670777;5585.636933599303;5597.6641401516035;5620.651043749512;5094.77073531692;5159.896802063938;5174.793008279665;5187.688738675692;5197.194434862565;5959.884241094435;6037.788677396314;6044.909326360108;6082.787102451393;6108.847278818021;4354.08564201518;4375.934360984587;4378.408914796525;4383.824647014116;4398.956010439887;5841.802430501737;6291.402316779899;6336.989929783362;6341.4926805855885;6345.714442850037;4441.844535441244;4457.710248833815;4463.437796771104;4463.620592249695;4467.67680805979;4506.481754598198;4523.547645235516;4530.728156310632;4544.590986811203;4555.749433781174;5111.773174438108;5143.7685304161405;5154.252633368215;5162.819301324435;5188.040212560688;6024.548158888101;6043.274807023097;6084.969575152184;6098.749756299948;6101.694915254219;1534.907905525672;1554.3937864232958;1578.928948197334;1581.2726145972981;1582.2168026826564;970.9319748276839;988.3328753091635;1004.2668851738272;1007.2948935745007;1010.2574607663362;5254.737263136828;5258.316418865599;5263.973525162556;5302.382773254195;5323.269937270403;5584.910942366199;5624.846906384181;5672.3550170450935;5680.920625081221;5689.345725241567;4991.500849915052;5239.685618862881;5272.2882410874045;5281.947180528223;5306.930297184102;2980.7913446059;2994.427925339214;2999.5200767877036;3000.7599392048583;3010.5935698680837;5525.861853453695;5715.685392359781;5748.367679555246;5772.336181339199;5798.304203495523;5622.297212848394;5631.211630371751;5671.858203544944;5675.5459563234845;5680.318361796611;6198.504119670506;6337.366263373718;6417.839554011187;6442.9355706443275;6465.870682586416;6242.531810114168;6284.968575157095;6297.842553936188;6304.71628776697;6341.207349081365;5952.315483719428;6190.597611155261;6197.907031394442;6213.502919766498;6229.688515574113;6485.697577359969;6498.805035848932;6510.7721229756735;6529.771457998947;6535.248446428722;4297.806598703129;4417.933730994102;4440.555944405663;4443.577860103285;4448.332750087513;6155.784547540815;6182;6197.644894939894;6333.20834895638;6353.491720662243;6102.176206212104;6109.725062372115;6122.2959359674205;6151.231096112986;6174.197354343906;5937.435943585352;6026.487748541423;6119.785807496894;6147.385261473817;6170.321264660974;5964.373740757332;6020.488258497901;6036.245469316335;6042.90935635957;6084.8783024340155;5971.58198926071;6061.605995610271;6100.23747031621;6189.721462534287;6194.5354098443695;6005.318510816978;6006.939930600638;6024.698765061817;6026.126211699367;6049.455549000611 03:35:47 INFO - PROCESS | 12752 | |5;yui.html;830.5888585150351;841.6465084664441;870.5516658920654;886.7206829848593;905.5743800413808;16330.428435004776;17632.38286659967;17673.823261767393;17688.734668979974;17720.025398603073;15925.203739813021;16124.919375403106;16148.111853848033;16152.83847161531;16159.192040397991;726.7238449389225;860.1656393298522;892.1123482135284;898.6180873128927;901.5897766516252;885.6767279942807;894.4052205283479;897.4839467306325;897.5467388968561;900.3427497926517;946.9337146399755;948.6632245552798;949.264320151881;950.2920324358328;951.3293128344535;941.948192849393;943.4669411782321;943.4810854030325;944.6174299408716;945.3618807305041;685.413971054751;688.2051230828401;692.2627401817049;693.6982412650514;695.8225652458641;15987.280572374271;16027.51917442467;16090.034597924163;16132.193390330554;16134.354625814953;14627.63430914225;14834.554963351116;15488.838337124647;15851.365945362171;16346.937449065892;12490.12569120162;12798.104132710714;13020.348982550835;13187.142835715647;13220.933895330461;4007.0984028593625;4037.656799172074;4083.3262511685534;4091.529474110486;4129.112240868198;82158.17841821625;84338;84538.57730711307;84824.57587712083;85233;11808.173427859967;11889.28664280146;11912.702182445335;12038.337891416057;12162.270263784201;22651.88674056619;22682;22707.659385109237;22738.545229095656;22753.97607107651;8679.52262625549;8846.33652476054;8956.462612243286;8966.058563850702;9336.906630933603;5057.747112644353;5210.140326846104;5213.035588416155;5258.132408152612;5378.256153194621;3756.6806821420487;3863.227354529049;3866.149447121629;3875.457435958968;3894.3963685628773;648.2577448821073;687.1685260834446;689.9931000689929;692.0657112897549;702.4205030849467;786.9921300787033;802.4463120446873;848.7156802471118;860.4364141487335;879.6085741844818;11013;11558.248713833575;11632.825507617392;11777;11813;722.3643193989255;794;853.38129855854;867.7700409391516;870.2820173357006;5311.840644780663;5383.43473935239;5428.375736790241;5430.755615997211;5533.806316778893;75015;77196;97348;118370.4081479587;122952.38523807503;64770.352296476434;89051;94476.52761736285;95635;96762.51618742003;89884.10115898888;90311;91229.08770912206;92179;93227.0677293232;9796.167325777104;9897.703068907944;9913.702588922344;10046.397216167054;10056.195504359488;54549.45450545602;56052.43947560472;56308.718456407456;56652;57829.421705782406;77490;79322;79735;81592.59203703943;83612;12846.550370736979;12892.41984110736;13077.149985250928;13142.540011099565;13626.250556219504;14593.489227876973;15095.245237738289;15297.388104475696;15393.923030384776;15432.151231682365;12889;13198.868011319764;13210.141340812816;13359.866401335863;13881.930590347387;13430.934763323265;13466.259355735529;13679.24764137982;14107.788383174055;14190.858091418953;13852;13871.306434678427;14047.508337208148;14203.928980355033;14337.06809057408;11138.721531961766;11305.71735706614;11399.829002565135;11447.599334023269;11572.6528204154;10671.679849604525;11366.204365694653;11399.943000284946;11436.542538298374;11441.599544015919 03:35:47 INFO - PROCESS | 12752 | __end_tp_report 03:35:47 INFO - PROCESS | 12752 | __start_cc_report 03:35:47 INFO - PROCESS | 12752 | _x_x_mozilla_cycle_collect,330 03:35:47 INFO - PROCESS | 12752 | __end_cc_report 03:35:47 INFO - PROCESS | 12752 | __startTimestamp1467282947425__endTimestamp 03:35:47 INFO - PROCESS | 12752 | 03:35:47 INFO - PROCESS | 12752 | ------- Summary: start ------- 03:35:47 INFO - PROCESS | 12752 | Number of tests: 6 03:35:47 INFO - PROCESS | 12752 | 03:35:47 INFO - PROCESS | 12752 | [#0] dojo.html Cycles:165 Average:5757.83 Median:6008.58 stddev:655.60 (10.9%) stddev-sans-first:630.18 03:35:47 INFO - PROCESS | 12752 | Values: 3365.7 3444.5 3444.9 3457.0 3477.6 5737.1 5764.3 5779.3 5795.1 5839.8 5783.9 5812.1 5836.9 5871.6 5873.2 5224.5 5262.8 5305.1 5308.8 5320.7 5117.6 5167.4 5218.6 5348.4 5357.3 5123.6 5159.8 5182.3 5185.4 5193.3 5195.1 5246.6 5259.2 5265.2 5285.6 5083.7 5145.2 5204.8 5258.2 5273.6 5515.0 5566.8 5579.3 5589.7 5605.0 6121.2 6141.1 6179.3 6180.8 6191.1 6008.6 6129.8 6213.3 6224.7 6246.9 5746.5 5819.9 5825.3 5828.0 5885.3 6323.3 6325.3 6356.4 6365.7 6370.1 5884.4 5968.0 5990.9 6050.2 6106.8 6186.6 6190.2 6204.9 6207.9 6222.3 6096.9 6112.7 6161.7 6165.9 6196.3 6040.7 6090.7 6133.8 6141.9 6171.2 4714.0 4738.6 4740.2 4756.0 4757.8 4217.4 4233.4 4245.0 4255.6 4263.4 5739.5 5857.8 5925.3 5938.8 5959.3 6130.2 6149.9 6163.9 6188.9 6263.3 5749.7 5900.6 5902.2 5910.4 5923.9 5287.7 5315.5 5325.3 5356.9 5365.1 6398.6 6458.2 6487.4 6494.6 6515.0 6119.6 6147.3 6275.4 6281.9 6288.4 6404.7 6506.0 6513.7 6519.2 6546.2 6161.9 6165.4 6184.2 6208.6 6234.1 6139.7 6169.9 6219.4 6316.0 6342.6 6071.1 6117.6 6121.0 6156.0 6174.1 6085.1 6106.6 6144.6 6160.3 6166.9 5823.3 5937.9 5939.7 5986.1 6063.1 6062.2 6124.8 6161.9 6201.9 6203.3 6032.9 6040.7 6046.2 6050.5 6052.2 03:35:47 INFO - PROCESS | 12752 | 03:35:47 INFO - PROCESS | 12752 | [#1] ext.html Cycles:180 Average:67942.81 Median:45597.00 stddev:140820.49 (308.8%) stddev-sans-first:141124.77 03:35:47 INFO - PROCESS | 12752 | Values: 603.5 668.5 670.8 672.3 678.8 42648.4 46814.0 46969.0 47036.5 47322.8 46707.3 47203.3 47272.0 47288.1 47382.5 8981.1 9049.8 9089.8 9140.4 9154.1 8931.7 9015.2 9049.7 9074.4 9089.7 9121.8 9121.8 9133.5 9136.8 9169.0 9112.5 9124.0 9125.9 9132.3 9154.4 9002.3 9109.0 9111.5 9114.0 9156.8 43623.0 43639.8 43660.6 44011.1 44058.0 3805.3 4113.1 4150.8 4263.3 4456.9 34454.5 34908.7 35274.5 35294.3 35442.8 4539.2 4589.5 4608.6 4706.8 4708.9 382885.0 384936.0 388175.0 388193.0 388782.0 68014.7 68448.3 68524.7 68652.7 68863.3 39566.0 40585.2 40714.8 42877.6 43177.0 16229.2 16367.4 16418.0 16433.8 16481.3 12665.2 12731.1 12738.2 12844.8 12849.3 21881.7 22222.8 22277.7 22326.2 22376.3 7812.3 7952.5 8042.5 8051.6 8139.0 983.5 1079.2 1090.3 1091.1 1175.2 33206.8 33360.8 33441.5 33943.3 34533.5 1132.5 1206.9 1217.1 1228.7 1244.7 18033.8 18184.5 18190.8 18334.8 18344.3 561347.0 583235.0 591208.0 592063.0 886569.0 66285.3 66886.3 66953.0 67409.7 67657.3 1456.5 1503.7 1512.4 1515.9 1521.5 34057.3 34524.0 34819.8 34994.7 35173.8 57877.7 58878.4 58882.0 58938.0 59022.4 502443.0 509874.0 510711.0 511891.0 512462.0 31846.5 31947.7 31990.8 32305.2 32396.4 54368.0 54439.7 54849.0 54945.0 55361.4 45015.3 45644.0 45962.8 46029.5 46991.5 44897.8 45550.0 45698.8 46865.3 47204.5 45090.3 45259.8 46100.8 46153.5 46189.1 38127.3 38692.8 38806.0 39523.4 39848.4 30323.2 30546.5 30786.8 31159.0 31208.2 03:35:47 INFO - PROCESS | 12752 | 03:35:47 INFO - PROCESS | 12752 | [#2] jquery.html Cycles:180 Average:30168.05 Median:2033.30 stddev:80671.32 (3967.5%) stddev-sans-first:80866.71 03:35:47 INFO - PROCESS | 12752 | Values: 421.1 424.2 426.2 426.9 435.9 19180.7 19329.9 20178.0 20531.8 20534.3 19418.3 19426.7 19660.9 20467.7 20469.7 5824.4 5945.9 5966.7 5982.3 6042.2 5743.6 5846.1 5948.4 5952.5 6004.5 5844.8 5859.3 5885.9 5889.6 5913.6 5706.3 5829.1 5850.8 5907.8 5925.7 5284.7 5296.3 5319.0 5332.8 5340.6 23717.2 23883.4 24139.5 24443.5 24450.6 2448.1 2538.3 2541.3 2567.6 2605.9 15352.8 15557.8 15595.7 15723.8 15794.8 1993.2 2011.1 2029.3 2037.3 2068.7 75430.0 76166.2 77909.2 83455.6 85326.0 10353.9 10496.1 10648.2 10873.4 10968.4 14345.6 14500.0 14506.1 14532.5 14623.3 7951.8 8171.1 8218.0 8233.0 8322.5 7796.1 7855.0 7884.1 8029.8 8040.8 4327.8 4424.9 4630.5 4666.6 4999.7 2040.3 2109.4 2174.2 2174.6 2180.5 965.9 1016.1 1039.2 1039.4 1042.2 18867.1 19600.6 19875.4 19975.6 20491.3 1061.1 1078.1 1087.3 1093.7 1123.2 10935.7 11281.4 11383.0 11392.1 11584.5 469685.0 480782.0 494357.0 496296.0 497663.0 91750.5 106149.5 109041.5 109794.5 109953.0 1079.8 1098.9 1124.7 1133.9 1139.7 10782.6 18271.6 18797.9 18884.3 18930.4 40847.4 45554.1 46254.3 46361.3 47071.5 60525.7 60568.7 61319.0 62925.0 63316.0 12766.2 13272.7 13488.6 13697.9 13760.3 14503.0 14559.7 14560.4 14785.6 14844.8 12586.2 12965.0 13019.2 13269.3 13286.2 12418.4 12558.6 12776.7 12781.3 13027.4 12767.4 13022.3 13063.9 13240.3 13584.6 13528.4 13578.0 13627.5 13755.2 13795.8 12602.2 12768.9 12971.5 12972.8 13010.9 03:35:47 INFO - PROCESS | 12752 | 03:35:47 INFO - PROCESS | 12752 | [#3] mootools.html Cycles:180 Average:6088.61 Median:4779.74 stddev:3510.07 (73.4%) stddev-sans-first:3503.61 03:35:47 INFO - PROCESS | 12752 | Values: 1586.5 1599.4 1816.0 1921.9 1948.7 8381.9 8949.3 9090.1 9615.1 9693.9 9074.8 9215.3 9359.1 9379.8 9388.7 2387.7 2438.6 2442.6 2444.6 2446.3 2346.3 2370.2 2384.7 2388.5 2395.2 2374.4 2389.5 2407.6 2417.5 2427.3 2363.9 2383.8 2385.9 2389.4 2418.6 2384.4 2396.2 2411.4 2420.6 2433.0 9154.2 9294.3 9315.9 9369.0 9433.1 5379.7 5466.7 5473.9 5493.6 5498.0 3448.1 3456.1 3514.2 3547.9 3555.0 1121.3 1130.4 1137.9 1154.2 1158.3 12838.4 12851.4 12896.3 12906.3 12937.5 4773.5 4786.0 4886.5 4915.5 4939.8 10751.8 10754.5 10772.6 10776.5 10791.4 5448.7 5463.9 5467.2 5478.2 5491.5 4548.8 4882.8 4954.4 5137.8 5210.7 4203.0 4233.2 4239.5 4263.6 4275.4 1259.2 1262.6 1270.8 1323.1 1325.2 961.4 1022.7 1034.5 1049.0 1050.9 6125.7 6192.3 6222.1 6227.5 6322.2 496.5 502.7 512.4 517.0 517.0 4203.3 4244.4 4251.5 4254.5 4275.4 11232.6 11440.5 11537.4 11592.8 11612.7 11473.4 11506.1 11530.9 11570.9 11573.4 8506.2 8572.0 8666.9 8668.2 8683.6 5940.3 6008.7 6026.6 6080.9 6084.7 10506.7 10679.4 10749.0 10750.9 10765.9 10674.4 10682.4 10938.0 10959.1 10962.0 7611.9 8021.8 8228.3 8234.6 8303.4 9179.7 9223.7 9251.8 9278.1 9333.6 8175.5 8311.8 8319.6 8344.7 8481.0 8010.0 8057.6 8171.4 8179.2 8188.3 7295.5 7296.6 7333.1 7348.5 7391.2 5340.0 5355.5 5372.4 5424.6 5434.9 5322.1 5366.9 5417.1 5445.8 5452.8 03:35:47 INFO - PROCESS | 12752 | 03:35:47 INFO - PROCESS | 12752 | [#4] prototype.html Cycles:180 Average:5015.07 Median:5623.57 stddev:1482.55 (26.4%) stddev-sans-first:1444.60 03:35:47 INFO - PROCESS | 12752 | Values: 340.7 347.4 352.1 358.1 362.8 5743.1 5774.7 5848.8 5856.0 5872.9 5646.8 5705.8 5741.2 5768.6 5777.8 4239.6 4532.0 4533.8 4543.0 4543.3 4384.5 4390.2 4390.7 4404.7 4416.7 4341.3 4365.6 4369.3 4370.2 4378.1 4389.8 4408.6 4414.6 4415.3 4427.2 3792.9 3964.9 3965.7 3966.3 3978.2 5569.2 5573.6 5585.6 5597.7 5620.7 5094.8 5159.9 5174.8 5187.7 5197.2 5959.9 6037.8 6044.9 6082.8 6108.8 4354.1 4375.9 4378.4 4383.8 4399.0 5841.8 6291.4 6337.0 6341.5 6345.7 4441.8 4457.7 4463.4 4463.6 4467.7 4506.5 4523.5 4530.7 4544.6 4555.7 5111.8 5143.8 5154.3 5162.8 5188.0 6024.5 6043.3 6085.0 6098.7 6101.7 1534.9 1554.4 1578.9 1581.3 1582.2 970.9 988.3 1004.3 1007.3 1010.3 5254.7 5258.3 5264.0 5302.4 5323.3 5584.9 5624.8 5672.4 5680.9 5689.3 4991.5 5239.7 5272.3 5281.9 5306.9 2980.8 2994.4 2999.5 3000.8 3010.6 5525.9 5715.7 5748.4 5772.3 5798.3 5622.3 5631.2 5671.9 5675.5 5680.3 6198.5 6337.4 6417.8 6442.9 6465.9 6242.5 6285.0 6297.8 6304.7 6341.2 5952.3 6190.6 6197.9 6213.5 6229.7 6485.7 6498.8 6510.8 6529.8 6535.2 4297.8 4417.9 4440.6 4443.6 4448.3 6155.8 6182.0 6197.6 6333.2 6353.5 6102.2 6109.7 6122.3 6151.2 6174.2 5937.4 6026.5 6119.8 6147.4 6170.3 5964.4 6020.5 6036.2 6042.9 6084.9 5971.6 6061.6 6100.2 6189.7 6194.5 6005.3 6006.9 6024.7 6026.1 6049.5 03:35:47 INFO - PROCESS | 12752 | 03:35:47 INFO - PROCESS | 12752 | [#5] yui.html Cycles:180 Average:21105.01 Median:5211.59 stddev:29190.04 (560.1%) stddev-sans-first:29232.23 03:35:47 INFO - PROCESS | 12752 | Values: 830.6 841.6 870.6 886.7 905.6 16330.4 17632.4 17673.8 17688.7 17720.0 15925.2 16124.9 16148.1 16152.8 16159.2 726.7 860.2 892.1 898.6 901.6 885.7 894.4 897.5 897.5 900.3 946.9 948.7 949.3 950.3 951.3 941.9 943.5 943.5 944.6 945.4 685.4 688.2 692.3 693.7 695.8 15987.3 16027.5 16090.0 16132.2 16134.4 14627.6 14834.6 15488.8 15851.4 16346.9 12490.1 12798.1 13020.3 13187.1 13220.9 4007.1 4037.7 4083.3 4091.5 4129.1 82158.2 84338.0 84538.6 84824.6 85233.0 11808.2 11889.3 11912.7 12038.3 12162.3 22651.9 22682.0 22707.7 22738.5 22754.0 8679.5 8846.3 8956.5 8966.1 9336.9 5057.7 5210.1 5213.0 5258.1 5378.3 3756.7 3863.2 3866.1 3875.5 3894.4 648.3 687.2 690.0 692.1 702.4 787.0 802.4 848.7 860.4 879.6 11013.0 11558.2 11632.8 11777.0 11813.0 722.4 794.0 853.4 867.8 870.3 5311.8 5383.4 5428.4 5430.8 5533.8 75015.0 77196.0 97348.0 118370.4 122952.4 64770.4 89051.0 94476.5 95635.0 96762.5 89884.1 90311.0 91229.1 92179.0 93227.1 9796.2 9897.7 9913.7 10046.4 10056.2 54549.5 56052.4 56308.7 56652.0 57829.4 77490.0 79322.0 79735.0 81592.6 83612.0 12846.6 12892.4 13077.1 13142.5 13626.3 14593.5 15095.2 15297.4 15393.9 15432.2 12889.0 13198.9 13210.1 13359.9 13881.9 13430.9 13466.3 13679.2 14107.8 14190.9 13852.0 13871.3 14047.5 14203.9 14337.1 11138.7 11305.7 11399.8 11447.6 11572.7 10671.7 11366.2 11399.9 11436.5 11441.6 03:35:47 INFO - PROCESS | 12752 | -------- Summary: end -------- 03:35:47 INFO - PROCESS | 12752 | 03:35:48 INFO - TEST-INFO | 12752: exit 0 03:35:48 INFO - TEST-OK | dromaeo_css | took 1110739ms 03:35:48 INFO - TEST-START | kraken 03:35:48 INFO - operating with platform_type : linux_ 03:35:48 INFO - Initialising browser for kraken test... 03:35:48 INFO - TEST-INFO | started process 12994 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeWABC2/profile http://localhost:47712/getInfo.html) 03:35:52 INFO - PROCESS | 12994 | __metrics Screen width/height:1600/1200 03:35:52 INFO - PROCESS | 12994 | colorDepth:24 03:35:52 INFO - PROCESS | 12994 | Browser inner width/height: 1024/697 03:35:52 INFO - PROCESS | 12994 | __metrics 03:35:53 ERROR - PROCESS | 12994 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2948: TypeError: this.tabs is undefined 03:35:54 INFO - TEST-INFO | 12994: exit 0 03:35:54 INFO - Browser initialized. 03:35:54 INFO - Running cycle 1/1 for kraken test... 03:35:54 INFO - TEST-INFO | started process 13122 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeWABC2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:36:05 INFO - PROCESS | 13122 | RSS: Main: 156344320 03:36:05 INFO - PROCESS | 13122 | 03:36:08 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47712/tests/kraken/driver.html?testName=audio-beat-detection) 03:36:09 INFO - PROCESS | 13122 | RSS: Main: 161542144 03:36:09 INFO - PROCESS | 13122 | 03:36:12 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47712/tests/kraken/driver.html?testName=audio-dft) 03:36:12 INFO - PROCESS | 13122 | RSS: Main: 160382976 03:36:12 INFO - PROCESS | 13122 | 03:36:15 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47712/tests/kraken/driver.html?testName=audio-fft) 03:36:16 INFO - PROCESS | 13122 | RSS: Main: 161292288 03:36:16 INFO - PROCESS | 13122 | 03:36:18 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47712/tests/kraken/driver.html?testName=audio-oscillator) 03:36:18 INFO - PROCESS | 13122 | RSS: Main: 160387072 03:36:18 INFO - PROCESS | 13122 | 03:36:21 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47712/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:36:21 INFO - PROCESS | 13122 | RSS: Main: 160231424 03:36:21 INFO - PROCESS | 13122 | 03:36:24 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47712/tests/kraken/driver.html?testName=imaging-darkroom) 03:36:25 INFO - PROCESS | 13122 | RSS: Main: 160571392 03:36:25 INFO - PROCESS | 13122 | 03:36:28 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47712/tests/kraken/driver.html?testName=imaging-desaturate) 03:36:29 INFO - PROCESS | 13122 | RSS: Main: 160976896 03:36:29 INFO - PROCESS | 13122 | 03:36:32 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47712/tests/kraken/driver.html?testName=json-parse-financial) 03:36:32 INFO - PROCESS | 13122 | RSS: Main: 161169408 03:36:32 INFO - PROCESS | 13122 | 03:36:34 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47712/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:36:34 INFO - PROCESS | 13122 | RSS: Main: 160849920 03:36:34 INFO - PROCESS | 13122 | 03:36:36 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47712/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:36:37 INFO - PROCESS | 13122 | RSS: Main: 160743424 03:36:37 INFO - PROCESS | 13122 | 03:36:39 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47712/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:36:40 INFO - PROCESS | 13122 | RSS: Main: 161157120 03:36:40 INFO - PROCESS | 13122 | 03:36:42 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47712/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:36:43 INFO - PROCESS | 13122 | RSS: Main: 161259520 03:36:43 INFO - PROCESS | 13122 | 03:36:45 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47712/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:36:46 INFO - PROCESS | 13122 | RSS: Main: 161218560 03:36:46 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | Cycle 1(1): loaded http://localhost:47712/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47712/tests/kraken/driver.html?testName=ai-astar) 03:36:48 INFO - PROCESS | 13122 | RSS: Main: 161677312 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | __start_tp_report 03:36:48 INFO - PROCESS | 13122 | _x_x_mozilla_page_load 03:36:48 INFO - PROCESS | 13122 | _x_x_mozilla_page_load_details 03:36:48 INFO - PROCESS | 13122 | |i|pagename|runs| 03:36:48 INFO - PROCESS | 13122 | |0;ai-astar;112;104;110;109;113;110;110;110;112;110 03:36:48 INFO - PROCESS | 13122 | |1;audio-beat-detection;155;130;153;171;126;156;154;135;204;124 03:36:48 INFO - PROCESS | 13122 | |2;audio-dft;161;162;162;162;165;162;163;163;160;159 03:36:48 INFO - PROCESS | 13122 | |3;audio-fft;76;73;76;173;77;75;74;75;162;74 03:36:48 INFO - PROCESS | 13122 | |4;audio-oscillator;85;85;141;84;84;87;134;84;84;147 03:36:48 INFO - PROCESS | 13122 | |5;imaging-gaussian-blur;118;117;118;117;117;117;118;117;117;118 03:36:48 INFO - PROCESS | 13122 | |6;imaging-darkroom;147;147;146;150;146;147;145;146;147;146 03:36:48 INFO - PROCESS | 13122 | |7;imaging-desaturate;95;95;96;96;95;95;94;96;95;95 03:36:48 INFO - PROCESS | 13122 | |8;json-parse-financial;51;51;51;54;52;54;62;52;52;54 03:36:48 INFO - PROCESS | 13122 | |9;json-stringify-tinderbox;61;61;60;61;62;61;61;61;65;60 03:36:48 INFO - PROCESS | 13122 | |10;stanford-crypto-aes;78;79;76;78;83;76;77;83;109;83 03:36:48 INFO - PROCESS | 13122 | |11;stanford-crypto-ccm;128;121;127;116;118;127;127;121;127;135 03:36:48 INFO - PROCESS | 13122 | |12;stanford-crypto-pbkdf2;156;158;154;153;154;152;156;154;154;160 03:36:48 INFO - PROCESS | 13122 | |13;stanford-crypto-sha256-iterative;62;66;63;61;62;61;62;67;60;60 03:36:48 INFO - PROCESS | 13122 | __end_tp_report 03:36:48 INFO - PROCESS | 13122 | __start_cc_report 03:36:48 INFO - PROCESS | 13122 | _x_x_mozilla_cycle_collect,877 03:36:48 INFO - PROCESS | 13122 | __end_cc_report 03:36:48 INFO - PROCESS | 13122 | __startTimestamp1467283008333__endTimestamp 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | ------- Summary: start ------- 03:36:48 INFO - PROCESS | 13122 | Number of tests: 14 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#0] ai-astar Cycles:10 Average:110.00 Median:110.00 stddev:2.45 (2.2%) stddev-sans-first:2.49 03:36:48 INFO - PROCESS | 13122 | Values: 112.0 104.0 110.0 109.0 113.0 110.0 110.0 110.0 112.0 110.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#1] audio-beat-detection Cycles:10 Average:150.80 Median:154.50 stddev:24.30 (15.7%) stddev-sans-first:25.72 03:36:48 INFO - PROCESS | 13122 | Values: 155.0 130.0 153.0 171.0 126.0 156.0 154.0 135.0 204.0 124.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.66 (1.0%) stddev-sans-first:1.73 03:36:48 INFO - PROCESS | 13122 | Values: 161.0 162.0 162.0 162.0 165.0 162.0 163.0 163.0 160.0 159.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#3] audio-fft Cycles:10 Average:93.50 Median:75.00 stddev:39.10 (52.1%) stddev-sans-first:40.96 03:36:48 INFO - PROCESS | 13122 | Values: 76.0 73.0 76.0 173.0 77.0 75.0 74.0 75.0 162.0 74.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#4] audio-oscillator Cycles:10 Average:101.50 Median:84.00 stddev:27.22 (32.4%) stddev-sans-first:28.20 03:36:48 INFO - PROCESS | 13122 | Values: 85.0 85.0 141.0 84.0 84.0 87.0 134.0 84.0 84.0 147.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 03:36:48 INFO - PROCESS | 13122 | Values: 118.0 117.0 118.0 117.0 117.0 117.0 118.0 117.0 117.0 118.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:1.34 (0.9%) stddev-sans-first:1.41 03:36:48 INFO - PROCESS | 13122 | Values: 147.0 147.0 146.0 150.0 146.0 147.0 145.0 146.0 147.0 146.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 03:36:48 INFO - PROCESS | 13122 | Values: 95.0 95.0 96.0 96.0 95.0 95.0 94.0 96.0 95.0 95.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:3.30 (6.2%) stddev-sans-first:3.40 03:36:48 INFO - PROCESS | 13122 | Values: 51.0 51.0 51.0 54.0 52.0 54.0 62.0 52.0 52.0 54.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.00 stddev:1.42 (2.3%) stddev-sans-first:1.50 03:36:48 INFO - PROCESS | 13122 | Values: 61.0 61.0 60.0 61.0 62.0 61.0 61.0 61.0 65.0 60.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:78.50 stddev:9.83 (12.5%) stddev-sans-first:10.31 03:36:48 INFO - PROCESS | 13122 | Values: 78.0 79.0 76.0 78.0 83.0 76.0 77.0 83.0 109.0 83.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#11] stanford-crypto-ccm Cycles:10 Average:124.70 Median:127.00 stddev:5.64 (4.4%) stddev-sans-first:5.85 03:36:48 INFO - PROCESS | 13122 | Values: 128.0 121.0 127.0 116.0 118.0 127.0 127.0 121.0 127.0 135.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.10 Median:155.00 stddev:2.42 (1.6%) stddev-sans-first:2.55 03:36:48 INFO - PROCESS | 13122 | Values: 156.0 158.0 154.0 153.0 154.0 152.0 156.0 154.0 154.0 160.0 03:36:48 INFO - PROCESS | 13122 | 03:36:48 INFO - PROCESS | 13122 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.00 stddev:2.37 (3.8%) stddev-sans-first:2.51 03:36:48 INFO - PROCESS | 13122 | Values: 62.0 66.0 63.0 61.0 62.0 61.0 62.0 67.0 60.0 60.0 03:36:48 INFO - PROCESS | 13122 | -------- Summary: end -------- 03:36:48 INFO - PROCESS | 13122 | 03:36:49 INFO - TEST-INFO | 13122: exit 0 03:36:49 INFO - TEST-OK | kraken | took 61256ms 03:36:49 INFO - SUITE-END | took 1171s 03:36:49 INFO - Completed test suite (00:19:32) 03:36:49 INFO - javascript benchmark 03:36:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7743.569190674082, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3365.6802603752644, 3444.4833275008755, 3444.896653100406, 3457.0, 3477.565304336958, 5737.13942908564, 5764.279465066867, 5779.335376431712, 5795.072788353864, 5839.79560715375, 5783.855403614912, 5812.070068788995, 5836.941630583693, 5871.618344807584, 5873.236479257701, 5224.529792318691, 5262.789488420459, 5305.071612467803, 5308.787648494055, 5320.680759154463, 5117.5650069744115, 5167.4057483389515, 5218.556422704075, 5348.358197016363, 5357.303550538444, 5123.641345105844, 5159.7678104485385, 5182.326297581328, 5185.429602743714, 5193.298904647862, 5195.090859099661, 5246.632735708521, 5259.21111833228, 5265.183896496049, 5285.6300058995885, 5083.72039537829, 5145.228215767627, 5204.843854684365, 5258.237555554461, 5273.630845840793, 5515.034868897927, 5566.777328906839, 5579.274694289757, 5589.664620122806, 5605.0, 6121.234845644294, 6141.109539116803, 6179.3202747697715, 6180.81457556274, 6191.102290167901, 6008.579399442041, 6129.754809807602, 6213.316535181127, 6224.688765561704, 6246.875562398766, 5746.454086861741, 5819.883602327972, 5825.330087040003, 5827.970860145672, 5885.323187833411, 6323.336049714806, 6325.272593651789, 6356.364363563699, 6365.745370185141, 6370.0763389308295, 5884.382139875252, 5968.045112782021, 5990.850228744316, 6050.2437195350585, 6106.78626248088, 6186.566940314135, 6190.226221722285, 6204.937950620436, 6207.937920620826, 6222.346653601307, 6096.8780624388155, 6112.663803490851, 6161.661108639068, 6165.938340616536, 6196.256449226122, 6040.697965101727, 6090.725917333731, 6133.82435864808, 6141.884937092159, 6171.197744293213, 4713.95286047142, 4738.620910327166, 4740.19416699162, 4755.95244047562, 4757.785899634525, 4217.388478670576, 4233.407322974786, 4244.957550424518, 4255.5744425557195, 4263.381809637647, 5739.512141468022, 5857.7656893724625, 5925.288965324103, 5938.792142275, 5959.344472108065, 6130.1724267224245, 6149.938500615026, 6163.876722465526, 6188.907166392508, 6263.342349053375, 5749.712514374348, 5900.586958912921, 5902.2327097477055, 5910.408959104056, 5923.881522369615, 5287.709175995357, 5315.495027972336, 5325.2810870531985, 5356.892862142813, 5365.141577347606, 6398.584092034003, 6458.192725909262, 6487.3836985486305, 6494.577852439576, 6514.990176522647, 6119.602225855305, 6147.262328520606, 6275.3724627536885, 6281.874362512815, 6288.434040936155, 6404.71178796946, 6506.0, 6513.73945042193, 6519.185101862267, 6546.214454265519, 6161.938380616316, 6165.445109940127, 6184.226971628546, 6208.627482351073, 6234.096056071753, 6139.693015349126, 6169.907451388322, 6219.40915613016, 6316.021016742412, 6342.587731797418, 6071.119687645355, 6117.571769976059, 6121.0, 6156.045812899008, 6174.073888916699, 6085.087236914498, 6106.6336019838955, 6144.600600960924, 6160.3223645398075, 6166.876662466815, 5823.301203855564, 5937.851553711192, 5939.703014849154, 5986.102084687332, 6063.120847477003, 6062.151298818258, 6124.846878828065, 6161.876762464815, 6201.937980620317, 6203.2556093269095, 6032.8793424132145, 6040.728167232573, 6046.2139921809885, 6050.515958723379, 6052.243469566304], "value": 5714.175747740879, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.471962033221, 668.5086461450829, 670.829402692302, 672.3008071605531, 678.8302924268933, 42648.36027459591, 46813.999999999956, 46969.0, 47036.52963470373, 47322.763386183105, 46707.299390509084, 47203.29195062077, 47272.0, 47288.0542389152, 47382.52617473824, 8981.146791054856, 9049.819003619923, 9089.772755681095, 9140.405873618227, 9154.130357616015, 8931.73204803857, 9015.188633023026, 9049.728508144766, 9074.410163339395, 9089.727308180733, 9121.771955701095, 9121.817563648723, 9133.543322833864, 9136.817263654722, 9169.03725108864, 9112.544372781334, 9124.0419755926, 9125.908740912606, 9132.269418446542, 9154.359194856363, 9002.324825638103, 9109.0, 9111.453312801254, 9114.0, 9156.816863662689, 43623.0, 43639.78180109111, 43660.56339436597, 44011.11977760459, 44058.0, 3805.296020236265, 4113.136241389312, 4150.771707556083, 4263.253930562139, 4456.8885777855485, 34454.483182752025, 34908.6509134908, 35274.470882936774, 35294.294114117576, 35442.8227858859, 4539.245247403271, 4589.472210695746, 4608.585227329557, 4706.811727530895, 4708.905821883577, 382885.0, 384936.0, 388175.0, 388193.0, 388781.99999999715, 68014.65992670105, 68448.31551684519, 68524.6573767128, 68652.65673671699, 68863.3113668857, 39566.0, 40585.1882962345, 40714.79642601827, 42877.57122428798, 43177.0, 16229.188540572924, 16367.42714005004, 16418.0, 16433.83566164347, 16481.34074637025, 12665.240085594894, 12731.10882238253, 12738.172018818746, 12844.807327890088, 12849.293288869016, 21881.67177492339, 22222.777772222395, 22277.66583501249, 22326.21858234954, 22376.32871013872, 7812.296893279632, 7952.522848629101, 8042.477238979448, 8051.557164356017, 8139.0, 983.5328219095873, 1079.1582565598846, 1090.3022065877844, 1091.050785816329, 1175.1538891997918, 33206.83396583002, 33360.83319583387, 33441.49837752436, 33943.32113357719, 34533.48199777005, 1132.5356603792407, 1206.897413719826, 1217.1236709569273, 1228.7173949991552, 1244.7261602447447, 18033.819661803478, 18184.545386365447, 18190.818091819176, 18334.81665183331, 18344.266229350676, 561347.0, 583235.0, 591208.0, 592063.0, 886569.0, 66285.33714662888, 66886.33113668898, 66953.0, 67409.66295168591, 67657.32342676609, 1456.533909149062, 1503.7293287208188, 1512.3648067811546, 1515.8635722785002, 1521.4674863797582, 34057.318853623285, 34523.96428107161, 34819.82590087033, 34994.650053499136, 35173.824130879184, 57877.71061144836, 58878.411215887296, 58882.0, 58938.0, 59022.40977590169, 502443.0, 509874.0, 510711.0, 511891.0, 512462.0, 31846.52230216595, 31947.68052319447, 31990.84004579962, 32305.192370190933, 32396.35207295888, 54368.0, 54439.72780136074, 54849.0, 54945.0, 55361.44638553563, 45015.324770127816, 45644.0, 45962.77018614885, 46029.53970460386, 46991.530084698716, 44897.775511122236, 45550.0, 45698.77150614226, 46865.297020545404, 47204.52795472139, 45090.323645146, 45259.773701131286, 46100.76949615365, 46153.538464616264, 46189.07621847477, 38127.300789697925, 38692.80653596714, 38806.0, 39523.40714889221, 39848.402273966494, 30323.241918952204, 30546.541801873434, 30786.846065770424, 31159.0, 31208.21979450532], "value": 21649.626862852834, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.0799403303781, 424.1749796645522, 426.22852636727515, 426.94236278102454, 435.8670605465332, 19180.712289315656, 19329.903350483244, 20178.0, 20531.794682053176, 20534.281300154496, 19418.320358787445, 19426.708599370984, 19660.901695491502, 20467.692984605204, 20469.692954605656, 5824.446677565635, 5945.940540594604, 5966.67183304918, 5982.282126144869, 6042.184305118819, 5743.597948143632, 5846.123081537782, 5948.375420580842, 5952.494038006775, 6004.54965877561, 5844.795432159854, 5859.267591551056, 5885.911711324312, 5889.617174883619, 5913.645181289115, 5706.343770466408, 5829.12563115534, 5850.824475265727, 5907.7636894524385, 5925.703714814242, 5284.735763211824, 5296.311479507678, 5319.042572336978, 5332.840014799563, 5340.599455040849, 23717.169899053624, 23883.402914927094, 24139.517209655885, 24443.511129777482, 24450.633240501407, 2448.09420514409, 2538.263903467992, 2541.301142185895, 2567.5763499022637, 2605.908793192248, 15352.846471535366, 15557.766633500507, 15595.688086238326, 15723.764143537855, 15794.842051579568, 1993.2027189124467, 2011.095007246745, 2029.2796057399557, 2037.327681864981, 2068.720722702431, 75430.0, 76166.23833761702, 77909.22090779133, 83455.582722086, 85326.0, 10353.896461035443, 10496.107830834311, 10648.201384896009, 10873.40196289196, 10968.396738179477, 14345.64135896611, 14500.0, 14506.057106288057, 14532.491362802462, 14623.268836558342, 7951.846982187551, 8171.10117887032, 8218.0, 8232.958835205785, 8322.542260175627, 7796.142424333319, 7854.960725196337, 7884.132745398002, 8029.759107226792, 8040.758777236692, 4327.826886924488, 4424.933625995613, 4630.490646028934, 4666.60333871618, 4999.650024498323, 2040.3470889315574, 2109.398821335912, 2174.184680744721, 2174.6411842045886, 2180.476685595477, 965.8696076029646, 1016.0753714120116, 1039.2153923787644, 1039.3504059962524, 1042.166266986423, 18867.05664716759, 19600.607987840165, 19875.403737887886, 19975.60048799016, 20491.28280510175, 1061.0821639281965, 1078.0567003871613, 1087.2601045365793, 1093.6718984304678, 1123.152020224725, 10935.671929842118, 11281.379524126252, 11383.0, 11392.145589080852, 11584.47869845842, 469685.0, 480782.0, 494357.0, 496296.0, 497663.0, 91750.54124729334, 106149.46925265325, 109041.45479272712, 109794.45102774435, 109953.0, 1079.8434227037035, 1098.9175811814146, 1124.668222874254, 1133.9149563782748, 1139.7492551638793, 10782.60522202868, 18271.634567308844, 18797.90601046986, 18884.33904813325, 18930.43208703741, 40847.38728919009, 45554.08891822079, 46254.306185406575, 46361.304580430646, 47071.52928470672, 60525.69737151286, 60568.69715651393, 61319.0, 62925.0, 63316.0, 12766.17019893704, 13272.734545308847, 13488.595342139752, 13697.931510342385, 13760.31198440094, 14503.0, 14559.708805824037, 14560.41758329655, 14785.630359241106, 14844.7773283403, 12586.18189817659, 12965.0, 13019.218846869218, 13269.274994250685, 13286.202827829975, 12418.37908104573, 12558.623241302776, 12776.6805829855, 12781.297028663514, 13027.413766380347, 12767.425465853874, 13022.348882555645, 13063.934680326538, 13240.271785051915, 13584.592462226148, 13528.39122239482, 13578.0, 13627.454901803816, 13755.243461609307, 13795.79306310386, 12602.180858244184, 12768.872311276768, 12971.5459958901, 12972.805407919077, 13010.869891301345], "value": 9954.996933584614, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1586.539903428006, 1599.360255897641, 1815.972760408594, 1921.8846869187842, 1948.658984677681, 8381.91618083819, 8949.28405727542, 9090.090990900906, 9615.134637882598, 9693.903060969387, 9074.818503629924, 9215.308851836106, 9359.110884465965, 9379.812403751921, 9388.718338449857, 2387.701537307832, 2438.6463962725393, 2442.572549803786, 2444.5966415541416, 2446.2905757330354, 2346.3195673254736, 2370.2178281167257, 2384.6899903012586, 2388.486475407787, 2395.209580838319, 2374.394529395002, 2389.545986262613, 2407.590709579367, 2417.468157005465, 2427.3446169534213, 2363.869987150706, 2383.8092952563925, 2385.85684858909, 2389.4026493376655, 2418.5525677749583, 2384.4158181245534, 2396.1733202045266, 2411.4333131714034, 2420.612701967694, 2433.0267892842826, 9154.176124148791, 9294.256459483226, 9315.860262096074, 9368.95315523418, 9433.103855133751, 5379.731013449351, 5466.699331536764, 5473.890522189574, 5493.587980901449, 5498.037843377433, 3448.137965508623, 3456.084137703511, 3514.1741690702665, 3547.8935631931085, 3555.0, 1121.2991880074953, 1130.3556972525664, 1137.9032782213521, 1154.2093665838843, 1158.3108050709898, 12838.358082095858, 12851.35743212845, 12896.290704011275, 12906.290154041524, 12937.48250069996, 4773.498782627844, 4785.976070119627, 4886.462489126193, 4915.459299477055, 4939.802407903644, 10751.78496430067, 10754.462276886125, 10772.569097236023, 10776.461176941279, 10791.352518848895, 5448.727563621803, 5463.945360546423, 5467.1799230115, 5478.205660179252, 5491.505764481136, 4548.840790572314, 4882.804687812519, 4954.430240522314, 5137.820176293886, 5210.687358758487, 4203.0, 4233.238017156879, 4239.533651298355, 4263.616274535245, 4275.358696195595, 1259.1563652352945, 1262.6275248801442, 1270.8220849081135, 1323.0672375974914, 1325.1982550556968, 961.3943215774018, 1022.6625213679467, 1034.4517405774952, 1049.0086867909904, 1050.9316894401838, 6125.724342404603, 6192.349803270592, 6222.066689996446, 6227.532935029801, 6322.178116844781, 496.48861672477403, 502.6607040247818, 512.4363200479443, 517.0, 517.0408891506277, 4203.348480985452, 4244.427002354642, 4251.511076226274, 4254.468191476066, 4275.444192254987, 11232.606858760068, 11440.485178167002, 11537.423128843524, 11592.826107608393, 11612.6516204514, 11473.368964706855, 11506.137039722054, 11530.88469115315, 11570.884291157148, 11573.363465009505, 8506.191911768248, 8571.95714021426, 8666.913330866737, 8668.219860212484, 8683.56582170889, 5940.287165540163, 6008.699565021644, 6026.578139530367, 6080.8783824324155, 6084.665343406155, 10506.737331566772, 10679.359238445719, 10749.0, 10750.892491074988, 10765.946170269099, 10674.35953842772, 10682.412467314372, 10938.0, 10959.06847917936, 10962.0, 7611.885821712568, 8021.8395632088195, 8228.25945664893, 8234.588270586328, 8303.377246706643, 9179.724608261762, 9223.677171298972, 9251.76870578214, 9278.11857873501, 9333.626654933998, 8175.509469431853, 8311.792205194677, 8319.584020799057, 8344.666213351398, 8480.957595211985, 8009.959950200212, 8057.637406316612, 8171.42800003994, 8179.182081791773, 8188.2630563249595, 7295.489315747846, 7296.635168241461, 7333.083364579427, 7348.522346047704, 7391.223921488381, 5340.038793017294, 5355.518003379714, 5372.435894231049, 5424.6269697392145, 5434.891302174014, 5322.09524380864, 5366.892662146814, 5417.079096553674, 5445.809396671098, 5452.7546260417585], "value": 4780.936837315291, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.65423595051027, 347.4319487637712, 352.0564886105237, 358.102952104977, 362.7841434346563, 5743.138529220619, 5774.65352078875, 5848.766049358021, 5855.970720146393, 5872.882542349151, 5646.774129034834, 5705.77176912923, 5741.224934633823, 5768.567357448187, 5777.82666520004, 4239.6396306314, 4532.002959348946, 4533.750643714594, 4543.0459603483305, 4543.273076307792, 4384.473863136428, 4390.231709450849, 4390.692651514395, 4404.731563244932, 4416.668749843759, 4341.2836881914445, 4365.585269399402, 4369.257226271541, 4370.235208838457, 4378.1462614790125, 4389.824407023715, 4408.647308215351, 4414.646828253732, 4415.293553031532, 4427.203103441379, 3792.924141517182, 3964.9008774780573, 3965.7025723070597, 3966.2662407454714, 3978.204359128186, 5569.164625306236, 5573.554115670777, 5585.636933599303, 5597.6641401516035, 5620.651043749512, 5094.77073531692, 5159.896802063938, 5174.793008279665, 5187.688738675692, 5197.194434862565, 5959.884241094435, 6037.788677396314, 6044.909326360108, 6082.787102451393, 6108.847278818021, 4354.08564201518, 4375.934360984587, 4378.408914796525, 4383.824647014116, 4398.956010439887, 5841.802430501737, 6291.402316779899, 6336.989929783362, 6341.4926805855885, 6345.714442850037, 4441.844535441244, 4457.710248833815, 4463.437796771104, 4463.620592249695, 4467.67680805979, 4506.481754598198, 4523.547645235516, 4530.728156310632, 4544.590986811203, 4555.749433781174, 5111.773174438108, 5143.7685304161405, 5154.252633368215, 5162.819301324435, 5188.040212560688, 6024.548158888101, 6043.274807023097, 6084.969575152184, 6098.749756299948, 6101.694915254219, 1534.907905525672, 1554.3937864232958, 1578.928948197334, 1581.2726145972981, 1582.2168026826564, 970.9319748276839, 988.3328753091635, 1004.2668851738272, 1007.2948935745007, 1010.2574607663362, 5254.737263136828, 5258.316418865599, 5263.973525162556, 5302.382773254195, 5323.269937270403, 5584.910942366199, 5624.846906384181, 5672.3550170450935, 5680.920625081221, 5689.345725241567, 4991.500849915052, 5239.685618862881, 5272.2882410874045, 5281.947180528223, 5306.930297184102, 2980.7913446059, 2994.427925339214, 2999.5200767877036, 3000.7599392048583, 3010.5935698680837, 5525.861853453695, 5715.685392359781, 5748.367679555246, 5772.336181339199, 5798.304203495523, 5622.297212848394, 5631.211630371751, 5671.858203544944, 5675.5459563234845, 5680.318361796611, 6198.504119670506, 6337.366263373718, 6417.839554011187, 6442.9355706443275, 6465.870682586416, 6242.531810114168, 6284.968575157095, 6297.842553936188, 6304.71628776697, 6341.207349081365, 5952.315483719428, 6190.597611155261, 6197.907031394442, 6213.502919766498, 6229.688515574113, 6485.697577359969, 6498.805035848932, 6510.7721229756735, 6529.771457998947, 6535.248446428722, 4297.806598703129, 4417.933730994102, 4440.555944405663, 4443.577860103285, 4448.332750087513, 6155.784547540815, 6182.0, 6197.644894939894, 6333.20834895638, 6353.491720662243, 6102.176206212104, 6109.725062372115, 6122.2959359674205, 6151.231096112986, 6174.197354343906, 5937.435943585352, 6026.487748541423, 6119.785807496894, 6147.385261473817, 6170.321264660974, 5964.373740757332, 6020.488258497901, 6036.245469316335, 6042.90935635957, 6084.8783024340155, 5971.58198926071, 6061.605995610271, 6100.23747031621, 6189.721462534287, 6194.5354098443695, 6005.318510816978, 6006.939930600638, 6024.698765061817, 6026.126211699367, 6049.455549000611], "value": 4541.14401492963, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.5888585150351, 841.6465084664441, 870.5516658920654, 886.7206829848593, 905.5743800413808, 16330.428435004776, 17632.38286659967, 17673.823261767393, 17688.734668979974, 17720.025398603073, 15925.203739813021, 16124.919375403106, 16148.111853848033, 16152.83847161531, 16159.192040397991, 726.7238449389225, 860.1656393298522, 892.1123482135284, 898.6180873128927, 901.5897766516252, 885.6767279942807, 894.4052205283479, 897.4839467306325, 897.5467388968561, 900.3427497926517, 946.9337146399755, 948.6632245552798, 949.264320151881, 950.2920324358328, 951.3293128344535, 941.948192849393, 943.4669411782321, 943.4810854030325, 944.6174299408716, 945.3618807305041, 685.413971054751, 688.2051230828401, 692.2627401817049, 693.6982412650514, 695.8225652458641, 15987.280572374271, 16027.51917442467, 16090.034597924163, 16132.193390330554, 16134.354625814953, 14627.63430914225, 14834.554963351116, 15488.838337124647, 15851.365945362171, 16346.937449065892, 12490.12569120162, 12798.104132710714, 13020.348982550835, 13187.142835715647, 13220.933895330461, 4007.0984028593625, 4037.656799172074, 4083.3262511685534, 4091.529474110486, 4129.112240868198, 82158.17841821625, 84338.0, 84538.57730711307, 84824.57587712083, 85233.0, 11808.173427859967, 11889.28664280146, 11912.702182445335, 12038.337891416057, 12162.270263784201, 22651.88674056619, 22682.0, 22707.659385109237, 22738.545229095656, 22753.97607107651, 8679.52262625549, 8846.33652476054, 8956.462612243286, 8966.058563850702, 9336.906630933603, 5057.747112644353, 5210.140326846104, 5213.035588416155, 5258.132408152612, 5378.256153194621, 3756.6806821420487, 3863.227354529049, 3866.149447121629, 3875.457435958968, 3894.3963685628773, 648.2577448821073, 687.1685260834446, 689.9931000689929, 692.0657112897549, 702.4205030849467, 786.9921300787033, 802.4463120446873, 848.7156802471118, 860.4364141487335, 879.6085741844818, 11013.0, 11558.248713833575, 11632.825507617392, 11777.0, 11813.0, 722.3643193989255, 794.0, 853.38129855854, 867.7700409391516, 870.2820173357006, 5311.840644780663, 5383.43473935239, 5428.375736790241, 5430.755615997211, 5533.806316778893, 75015.0, 77196.0, 97348.0, 118370.4081479587, 122952.38523807503, 64770.352296476434, 89051.0, 94476.52761736285, 95635.0, 96762.51618742003, 89884.10115898888, 90311.0, 91229.08770912206, 92179.0, 93227.0677293232, 9796.167325777104, 9897.703068907944, 9913.702588922344, 10046.397216167054, 10056.195504359488, 54549.45450545602, 56052.43947560472, 56308.718456407456, 56652.0, 57829.421705782406, 77490.0, 79322.0, 79735.0, 81592.59203703943, 83612.0, 12846.550370736979, 12892.41984110736, 13077.149985250928, 13142.540011099565, 13626.250556219504, 14593.489227876973, 15095.245237738289, 15297.388104475696, 15393.923030384776, 15432.151231682365, 12889.0, 13198.868011319764, 13210.141340812816, 13359.866401335863, 13881.930590347387, 13430.934763323265, 13466.259355735529, 13679.24764137982, 14107.788383174055, 14190.858091418953, 13852.0, 13871.306434678427, 14047.508337208148, 14203.928980355033, 14337.06809057408, 11138.721531961766, 11305.71735706614, 11399.829002565135, 11447.599334023269, 11572.6528204154, 10671.679849604525, 11366.204365694653, 11399.943000284946, 11436.542538298374, 11441.599544015919], "value": 8062.693837239384, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 110.0, 109.0, 113.0, 110.0, 110.0, 110.0, 112.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 130.0, 153.0, 171.0, 126.0, 156.0, 154.0, 135.0, 204.0, 124.0], "value": 150.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 162.0, 165.0, 162.0, 163.0, 163.0, 160.0, 159.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 76.0, 173.0, 77.0, 75.0, 74.0, 75.0, 162.0, 74.0], "value": 93.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 141.0, 84.0, 84.0, 87.0, 134.0, 84.0, 84.0, 147.0], "value": 101.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 118.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 150.0, 146.0, 147.0, 145.0, 146.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 54.0, 52.0, 54.0, 62.0, 52.0, 52.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 61.0, 62.0, 61.0, 61.0, 61.0, 65.0, 60.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 76.0, 78.0, 83.0, 76.0, 77.0, 83.0, 109.0, 83.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 121.0, 127.0, 116.0, 118.0, 127.0, 127.0, 121.0, 127.0, 135.0], "value": 124.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 158.0, 154.0, 153.0, 154.0, 152.0, 156.0, 154.0, 154.0, 160.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 63.0, 61.0, 62.0, 61.0, 62.0, 67.0, 60.0, 60.0], "value": 62.4, "unit": "score"}]}]} 03:36:49 INFO - Return code: 0 03:36:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:36:49 ERROR - # TBPL SUCCESS # 03:36:49 INFO - Running post-action listener: _resource_record_post_action 03:36:49 INFO - ##### 03:36:49 INFO - ##### Finished run-tests step (success) 03:36:49 INFO - ##### 03:36:49 INFO - Running post-run listener: _resource_record_post_run 03:36:49 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 299008; Write bytes: 245813248; Read time: 260; Write time: 155104 03:36:49 INFO - TinderboxPrint: CPU usage
13.4% 03:36:49 INFO - TinderboxPrint: I/O read bytes / time
299,008 / 260 03:36:49 INFO - TinderboxPrint: I/O write bytes / time
245,813,248 / 155,104 03:36:49 INFO - TinderboxPrint: CPU idle
8,214.5 (86.6%) 03:36:49 INFO - TinderboxPrint: CPU user
1,215.8 (12.8%) 03:36:49 INFO - TinderboxPrint: Swap in / out
0 / 0 03:36:49 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 217088; Read time: 0; Write time: 56 03:36:49 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 274432; Write bytes: 242835456; Read time: 248; Write time: 151960 03:36:50 INFO - Running post-run listener: _upload_blobber_files 03:36:50 INFO - Blob upload gear active. 03:36:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:36:50 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 03:36:50 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'] 03:36:50 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 03:36:50 INFO - (blobuploader) - INFO - Open directory for files ... 03:36:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:36:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:36:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:51 INFO - (blobuploader) - INFO - Done attempting. 03:36:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:36:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:57 INFO - (blobuploader) - INFO - Done attempting. 03:36:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:36:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:57 INFO - (blobuploader) - INFO - Done attempting. 03:36:57 INFO - (blobuploader) - INFO - Iteration through files over. 03:36:57 INFO - Return code: 0 03:36:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:36:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:36:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec48304da34cab8a2c56aab0ac4743cfbe41995fb67e2975d2ff90f4a391e8cce13b7f195b785e60790f1a902d62dddaa1a57fd3031c6a1c49ef1646beefc381", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c92506ebc0a0272a7d2a51117e71ccd7ac0100d6e1b01480697af7e73322edf25375079df175848825400fb64c1dc4fd6e672e18c8f2f5009fefe6372d6faad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/618f9cabde5cae3e74ceac0b0d4038f312e936e8f105e2bf24fca750b7014fcc7d415bfea975e14e1de51684ed643cc7e0bd21343d4d9c7450a8cfd7136ef0be"} 03:36:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:36:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:36:57 INFO - Contents: 03:36:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec48304da34cab8a2c56aab0ac4743cfbe41995fb67e2975d2ff90f4a391e8cce13b7f195b785e60790f1a902d62dddaa1a57fd3031c6a1c49ef1646beefc381", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c92506ebc0a0272a7d2a51117e71ccd7ac0100d6e1b01480697af7e73322edf25375079df175848825400fb64c1dc4fd6e672e18c8f2f5009fefe6372d6faad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/618f9cabde5cae3e74ceac0b0d4038f312e936e8f105e2bf24fca750b7014fcc7d415bfea975e14e1de51684ed643cc7e0bd21343d4d9c7450a8cfd7136ef0be"} 03:36:57 INFO - Running post-run listener: copy_logs_to_upload_dir 03:36:57 INFO - Copying logs to upload dir... 03:36:57 INFO - mkdir: /builds/slave/test/build/upload/logs 03:36:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.447411 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-06-30 03:36:57.868989) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 03:36:57.872710) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288 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/ec48304da34cab8a2c56aab0ac4743cfbe41995fb67e2975d2ff90f4a391e8cce13b7f195b785e60790f1a902d62dddaa1a57fd3031c6a1c49ef1646beefc381", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c92506ebc0a0272a7d2a51117e71ccd7ac0100d6e1b01480697af7e73322edf25375079df175848825400fb64c1dc4fd6e672e18c8f2f5009fefe6372d6faad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/618f9cabde5cae3e74ceac0b0d4038f312e936e8f105e2bf24fca750b7014fcc7d415bfea975e14e1de51684ed643cc7e0bd21343d4d9c7450a8cfd7136ef0be"} build_url:https://queue.taskcluster.net/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011340 build_url: 'https://queue.taskcluster.net/v1/task/OfFnVg43QFqOBFVwtsHIhg/artifacts/public/build/firefox-49.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/ec48304da34cab8a2c56aab0ac4743cfbe41995fb67e2975d2ff90f4a391e8cce13b7f195b785e60790f1a902d62dddaa1a57fd3031c6a1c49ef1646beefc381", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c92506ebc0a0272a7d2a51117e71ccd7ac0100d6e1b01480697af7e73322edf25375079df175848825400fb64c1dc4fd6e672e18c8f2f5009fefe6372d6faad", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/618f9cabde5cae3e74ceac0b0d4038f312e936e8f105e2bf24fca750b7014fcc7d415bfea975e14e1de51684ed643cc7e0bd21343d4d9c7450a8cfd7136ef0be"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 03:36:57.898504) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 03:36:57.898838) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1467280527.971012-797127288 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 03:36:57.981308) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 03:36:57.981634) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 03:36:57.981965) ========= ========= Total master_lag: 0.19 =========