builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1470201420.38 results: success (0) buildid: 20160802193035 builduid: 7224e3fcfe154bd58fc3a4128c0bfa0d revision: ae7b718d8afa96ccd8552b142d46f06f37c953b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:00.383971) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:00.384389) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:00.384765) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:00.427530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:00.427833) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-02 22:17:01-- 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% 111M=0s 2016-08-02 22:17:02 (111 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127840 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:00.571158) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:00.571461) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020343 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:00.621262) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:00.621597) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ae7b718d8afa96ccd8552b142d46f06f37c953b5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ae7b718d8afa96ccd8552b142d46f06f37c953b5 --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=a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-02 22:17:02,178 truncating revision to first 12 chars 2016-08-02 22:17:02,178 Setting DEBUG logging. 2016-08-02 22:17:02,178 attempt 1/10 2016-08-02 22:17:02,178 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/ae7b718d8afa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-02 22:17:02,516 unpacking tar archive at: mozilla-inbound-ae7b718d8afa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.739073 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:01.377189) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:01.377602) ========= script_repo_revision: ae7b718d8afa96ccd8552b142d46f06f37c953b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:01.377997) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:01.378317) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 22:17:01.389612) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-02 22:17:01.389920) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:17:03 INFO - MultiFileLogger online at 20160802 22:17:03 in /builds/slave/test 22:17:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:17:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:17:03 INFO - {'append_to_log': False, 22:17:03 INFO - 'base_work_dir': '/builds/slave/test', 22:17:03 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:17:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:17:03 INFO - 'branch': 'Mozilla-Inbound', 22:17:03 INFO - 'buildbot_json_path': 'buildprops.json', 22:17:03 INFO - 'config_files': ('talos/linux_config.py',), 22:17:03 INFO - 'default_actions': ('clobber', 22:17:03 INFO - 'read-buildbot-config', 22:17:03 INFO - 'download-and-extract', 22:17:03 INFO - 'populate-webroot', 22:17:03 INFO - 'create-virtualenv', 22:17:03 INFO - 'install', 22:17:03 INFO - 'run-tests'), 22:17:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:17:03 INFO - 'download_minidump_stackwalk': True, 22:17:03 INFO - 'download_symbols': 'ondemand', 22:17:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:17:03 INFO - 'tooltool.py': '/tools/tooltool.py', 22:17:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:17:03 INFO - '/tools/misc-python/virtualenv.py')}, 22:17:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:17:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:17:03 INFO - 'installer_path': 'installer.exe', 22:17:03 INFO - 'log_level': 'info', 22:17:03 INFO - 'log_name': 'talos', 22:17:03 INFO - 'log_to_console': True, 22:17:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:17:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:17:03 INFO - 'opt_config_files': (), 22:17:03 INFO - 'pip_index': False, 22:17:03 INFO - 'sps_profile': False, 22:17:03 INFO - 'sps_profile_interval': 0, 22:17:03 INFO - 'suite': 'dromaeojs', 22:17:03 INFO - 'system_bits': '32', 22:17:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:17:03 INFO - 'title': 'talos-linux64-ix-064', 22:17:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:17:03 INFO - 'use_talos_json': True, 22:17:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:17:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:17:03 INFO - 'work_dir': 'build'} 22:17:03 INFO - [mozharness: 2016-08-03 05:17:03.042826Z] Running clobber step. 22:17:03 INFO - Running pre-action listener: _resource_record_pre_action 22:17:03 INFO - Running main action method: clobber 22:17:03 INFO - rmtree: /builds/slave/test/build 22:17:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:17:03 INFO - Running post-action listener: _resource_record_post_action 22:17:03 INFO - [mozharness: 2016-08-03 05:17:03.512529Z] Finished clobber step (success) 22:17:03 INFO - [mozharness: 2016-08-03 05:17:03.512632Z] Running read-buildbot-config step. 22:17:03 INFO - Running pre-action listener: _resource_record_pre_action 22:17:03 INFO - Running main action method: read_buildbot_config 22:17:03 INFO - Using buildbot properties: 22:17:03 INFO - { 22:17:03 INFO - "project": "", 22:17:03 INFO - "product": "firefox", 22:17:03 INFO - "script_repo_revision": "production", 22:17:03 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:17:03 INFO - "repository": "", 22:17:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:17:03 INFO - "buildid": "20160802193035", 22:17:03 INFO - "pgo_build": "True", 22:17:03 INFO - "basedir": "/builds/slave/test", 22:17:03 INFO - "buildnumber": 1343, 22:17:03 INFO - "slavename": "talos-linux64-ix-064", 22:17:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:17:03 INFO - "platform": "ubuntu64_hw", 22:17:03 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:17:03 INFO - "branch": "mozilla-inbound", 22:17:03 INFO - "repo_path": "integration/mozilla-inbound", 22:17:03 INFO - "revision": "ae7b718d8afa96ccd8552b142d46f06f37c953b5", 22:17:03 INFO - "stage_platform": "linux64-pgo", 22:17:03 INFO - "builduid": "7224e3fcfe154bd58fc3a4128c0bfa0d", 22:17:03 INFO - "slavebuilddir": "test" 22:17:03 INFO - } 22:17:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:17:03 INFO - Running post-action listener: _resource_record_post_action 22:17:03 INFO - [mozharness: 2016-08-03 05:17:03.515103Z] Finished read-buildbot-config step (success) 22:17:03 INFO - [mozharness: 2016-08-03 05:17:03.515193Z] Running download-and-extract step. 22:17:03 INFO - Running pre-action listener: _resource_record_pre_action 22:17:03 INFO - Running main action method: download_and_extract 22:17:03 INFO - mkdir: /builds/slave/test/build/tests 22:17:03 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')]} 22:17:03 INFO - https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:17:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:17:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:17:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:17:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:17:06 INFO - Downloaded 1361 bytes. 22:17:06 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:17:06 INFO - Using the following test package requirements: 22:17:06 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:17:06 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:17:06 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:17:06 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'jsshell-linux-x86_64.zip'], 22:17:06 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:17:06 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:17:06 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:17:06 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:17:06 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:17:06 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:17:06 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:06 INFO - https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:17:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:17:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:17:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:17:16 INFO - Downloaded 21811721 bytes. 22:17:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:17:17 INFO - Return code: 0 22:17:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:17 INFO - https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:17:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:17:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:17:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:17:18 INFO - Downloaded 21811721 bytes. 22:17:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:17:20 INFO - Return code: 0 22:17: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')]} 22:17:20 INFO - https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:17:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:17:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:17:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:17:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:17:22 INFO - Downloaded 14413723 bytes. 22:17:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:17:22 INFO - Return code: 0 22:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:22 INFO - https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:17:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:17:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:17:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:17:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:17:23 INFO - Downloaded 59400750 bytes. 22:17:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:17:23 INFO - mkdir: /builds/slave/test/properties 22:17:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:17:23 INFO - Writing to file /builds/slave/test/properties/build_url 22:17:23 INFO - Contents: 22:17:23 INFO - build_url:https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:17:24 INFO - Running post-action listener: _resource_record_post_action 22:17:24 INFO - Running post-action listener: set_extra_try_arguments 22:17:24 INFO - [mozharness: 2016-08-03 05:17:24.385411Z] Finished download-and-extract step (success) 22:17:24 INFO - [mozharness: 2016-08-03 05:17:24.385645Z] Running populate-webroot step. 22:17:24 INFO - Running pre-action listener: _resource_record_pre_action 22:17:24 INFO - Running main action method: populate_webroot 22:17:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:17:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:17:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:17:24 INFO - '--tppagecycles', 22:17:24 INFO - '7'], 22:17:24 INFO - 'tests': ['tsvgm']}}, 22:17:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:17:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:17:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:17:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:17:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:24 INFO - 'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:24 INFO - 'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['damp', 'tps']}, 22:17:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:24 INFO - 'tests': ['damp', 'tps']}, 22:17:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['dromaeo_dom']}, 22:17:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:17:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['basic_compositor_video']}, 22:17:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:17:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['a11yr', 22:17:24 INFO - 'ts_paint', 22:17:24 INFO - 'tpaint', 22:17:24 INFO - 'sessionrestore', 22:17:24 INFO - 'sessionrestore_no_auto_restore', 22:17:24 INFO - 'tabpaint']}, 22:17:24 INFO - 'other-e10s': {'tests': ['a11yr', 22:17:24 INFO - 'ts_paint', 22:17:24 INFO - 'tpaint', 22:17:24 INFO - 'sessionrestore', 22:17:24 INFO - 'sessionrestore_no_auto_restore', 22:17:24 INFO - 'tabpaint']}, 22:17:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:17:24 INFO - 'ts_paint', 22:17:24 INFO - 'tpaint', 22:17:24 INFO - 'sessionrestore', 22:17:24 INFO - 'sessionrestore_no_auto_restore', 22:17:24 INFO - 'tabpaint']}, 22:17:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:17:24 INFO - 'ts_paint', 22:17:24 INFO - 'tpaint', 22:17:24 INFO - 'sessionrestore', 22:17:24 INFO - 'sessionrestore_no_auto_restore', 22:17:24 INFO - 'tabpaint']}, 22:17:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['a11yr', 22:17:24 INFO - 'ts_paint', 22:17:24 INFO - 'tpaint', 22:17:24 INFO - 'sessionrestore', 22:17:24 INFO - 'sessionrestore_no_auto_restore', 22:17:24 INFO - 'tabpaint']}, 22:17:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['a11yr', 22:17:24 INFO - 'ts_paint', 22:17:24 INFO - 'tpaint', 22:17:24 INFO - 'sessionrestore', 22:17:24 INFO - 'sessionrestore_no_auto_restore', 22:17:24 INFO - 'tabpaint']}, 22:17:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['tsvgx', 22:17:24 INFO - 'tsvgr_opacity', 22:17:24 INFO - 'tart', 22:17:24 INFO - 'tscrollx', 22:17:24 INFO - 'cart']}, 22:17:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:17:24 INFO - 'tsvgr_opacity', 22:17:24 INFO - 'tart', 22:17:24 INFO - 'tscrollx', 22:17:24 INFO - 'cart']}, 22:17:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:24 INFO - 'talos_options': ['--disable-e10s'], 22:17:24 INFO - 'tests': ['tp5o']}, 22:17:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:24 INFO - 'tests': ['tp5o']}, 22:17:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:24 INFO - 'talos_options': ['--disable-e10s', 22:17:24 INFO - '--xperf_path', 22:17:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:24 INFO - 'tests': ['tp5n']}, 22:17:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:24 INFO - 'talos_options': ['--xperf_path', 22:17:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:24 INFO - 'tests': ['tp5n']}}, 22:17:24 INFO - 'talos.zip': {'path': '', 22:17:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:17:24 INFO - Running post-action listener: _resource_record_post_action 22:17:24 INFO - [mozharness: 2016-08-03 05:17:24.401977Z] Finished populate-webroot step (success) 22:17:24 INFO - [mozharness: 2016-08-03 05:17:24.402066Z] Running create-virtualenv step. 22:17:24 INFO - Running pre-action listener: _resource_record_pre_action 22:17:24 INFO - Running main action method: create_virtualenv 22:17:24 INFO - Creating virtualenv /builds/slave/test/build/venv 22:17:24 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 22:17:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:17:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:17:24 INFO - Using real prefix '/usr' 22:17:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:17:25 INFO - Installing distribute.............................................................................................................................................................................................done. 22:17:26 INFO - Installing pip.................done. 22:17:27 INFO - Return code: 0 22:17:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:17:27 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')]} 22:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:27 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')]} 22:17:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:27 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 0x12fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15bc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', '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 22:17:27 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 22:17:27 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 22:17:27 INFO - Using env: {'DISPLAY': ':0', 22:17:27 INFO - 'HOME': '/home/cltbld', 22:17:27 INFO - 'LANG': 'en_US.UTF-8', 22:17:27 INFO - 'LANGUAGE': 'en_US:en', 22:17:27 INFO - 'LOGNAME': 'cltbld', 22:17:27 INFO - 'MAIL': '/var/mail/cltbld', 22:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:27 INFO - 'MOZ_NO_REMOTE': '1', 22:17:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:27 INFO - 'NO_EM_RESTART': '1', 22:17:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:27 INFO - 'PWD': '/builds/slave/test', 22:17:27 INFO - 'SHELL': '/bin/bash', 22:17:27 INFO - 'SHLVL': '1', 22:17:27 INFO - 'TERM': 'linux', 22:17:27 INFO - 'TMOUT': '86400', 22:17:27 INFO - 'USER': 'cltbld', 22:17:27 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:27 INFO - '_': '/tools/buildbot/bin/python'} 22:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:27 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 22:17:27 INFO - Cleaning up... 22:17:27 INFO - Return code: 0 22:17:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:17:27 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')]} 22:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:27 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')]} 22:17:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:27 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 0x12fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15bc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', '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 22:17:27 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 22:17:27 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 22:17:27 INFO - Using env: {'DISPLAY': ':0', 22:17:27 INFO - 'HOME': '/home/cltbld', 22:17:27 INFO - 'LANG': 'en_US.UTF-8', 22:17:27 INFO - 'LANGUAGE': 'en_US:en', 22:17:27 INFO - 'LOGNAME': 'cltbld', 22:17:27 INFO - 'MAIL': '/var/mail/cltbld', 22:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:27 INFO - 'MOZ_NO_REMOTE': '1', 22:17:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:27 INFO - 'NO_EM_RESTART': '1', 22:17:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:27 INFO - 'PWD': '/builds/slave/test', 22:17:27 INFO - 'SHELL': '/bin/bash', 22:17:27 INFO - 'SHLVL': '1', 22:17:27 INFO - 'TERM': 'linux', 22:17:27 INFO - 'TMOUT': '86400', 22:17:27 INFO - 'USER': 'cltbld', 22:17:27 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:27 INFO - '_': '/tools/buildbot/bin/python'} 22:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:27 INFO - Downloading/unpacking psutil>=3.1.1 22:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:17:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:29 INFO - Installing collected packages: psutil 22:17:29 INFO - Running setup.py install for psutil 22:17:29 INFO - building 'psutil._psutil_linux' extension 22:17:29 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 22:17:29 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 22:17:29 INFO - building 'psutil._psutil_posix' extension 22:17:29 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 22:17:29 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 22:17:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:29 INFO - Successfully installed psutil 22:17:29 INFO - Cleaning up... 22:17:30 INFO - Return code: 0 22:17:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:30 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 0x12fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15bc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', '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 22:17:30 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 22:17:30 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 22:17:30 INFO - Using env: {'DISPLAY': ':0', 22:17:30 INFO - 'HOME': '/home/cltbld', 22:17:30 INFO - 'LANG': 'en_US.UTF-8', 22:17:30 INFO - 'LANGUAGE': 'en_US:en', 22:17:30 INFO - 'LOGNAME': 'cltbld', 22:17:30 INFO - 'MAIL': '/var/mail/cltbld', 22:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:30 INFO - 'MOZ_NO_REMOTE': '1', 22:17:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:30 INFO - 'NO_EM_RESTART': '1', 22:17:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:30 INFO - 'PWD': '/builds/slave/test', 22:17:30 INFO - 'SHELL': '/bin/bash', 22:17:30 INFO - 'SHLVL': '1', 22:17:30 INFO - 'TERM': 'linux', 22:17:30 INFO - 'TMOUT': '86400', 22:17:30 INFO - 'USER': 'cltbld', 22:17:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:30 INFO - '_': '/tools/buildbot/bin/python'} 22:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:17:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:17:32 INFO - Installing collected packages: mozsystemmonitor 22:17:32 INFO - Running setup.py install for mozsystemmonitor 22:17:32 INFO - Successfully installed mozsystemmonitor 22:17:32 INFO - Cleaning up... 22:17:32 INFO - Return code: 0 22:17:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:17: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')]} 22:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17: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')]} 22:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:32 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 0x12fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15bc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', '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 22:17:32 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 22:17:32 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 22:17:32 INFO - Using env: {'DISPLAY': ':0', 22:17:32 INFO - 'HOME': '/home/cltbld', 22:17:32 INFO - 'LANG': 'en_US.UTF-8', 22:17:32 INFO - 'LANGUAGE': 'en_US:en', 22:17:32 INFO - 'LOGNAME': 'cltbld', 22:17:32 INFO - 'MAIL': '/var/mail/cltbld', 22:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:32 INFO - 'MOZ_NO_REMOTE': '1', 22:17:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:32 INFO - 'NO_EM_RESTART': '1', 22:17:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:32 INFO - 'PWD': '/builds/slave/test', 22:17:32 INFO - 'SHELL': '/bin/bash', 22:17:32 INFO - 'SHLVL': '1', 22:17:32 INFO - 'TERM': 'linux', 22:17:32 INFO - 'TMOUT': '86400', 22:17:32 INFO - 'USER': 'cltbld', 22:17:32 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:32 INFO - '_': '/tools/buildbot/bin/python'} 22:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:32 INFO - Downloading/unpacking jsonschema==2.5.1 22:17:32 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 22:17:32 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 22:17:32 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 22:17:32 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 22:17:32 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 22:17:32 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 22:17:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:17:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:17:34 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 22:17:34 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 22:17:34 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 22:17:34 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 22:17:34 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 22:17:34 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 22:17:34 INFO - Downloading functools32-3.2.3-2.tar.gz 22:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:17:34 INFO - warning: no files found matching '*.txt' 22:17:34 INFO - no previously-included directories found matching 'build' 22:17:34 INFO - no previously-included directories found matching 'dist' 22:17:34 INFO - no previously-included directories found matching '.git*' 22:17:34 INFO - Installing collected packages: jsonschema, functools32 22:17:34 INFO - Running setup.py install for functools32 22:17:34 INFO - warning: no files found matching '*.txt' 22:17:34 INFO - no previously-included directories found matching 'build' 22:17:34 INFO - no previously-included directories found matching 'dist' 22:17:34 INFO - no previously-included directories found matching '.git*' 22:17:34 INFO - Successfully installed jsonschema functools32 22:17:34 INFO - Cleaning up... 22:17:34 INFO - Return code: 0 22:17:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15bc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', '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 22:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:17:34 INFO - Using env: {'DISPLAY': ':0', 22:17:34 INFO - 'HOME': '/home/cltbld', 22:17:34 INFO - 'LANG': 'en_US.UTF-8', 22:17:34 INFO - 'LANGUAGE': 'en_US:en', 22:17:34 INFO - 'LOGNAME': 'cltbld', 22:17:34 INFO - 'MAIL': '/var/mail/cltbld', 22:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:34 INFO - 'MOZ_NO_REMOTE': '1', 22:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:34 INFO - 'NO_EM_RESTART': '1', 22:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:34 INFO - 'PWD': '/builds/slave/test', 22:17:34 INFO - 'SHELL': '/bin/bash', 22:17:34 INFO - 'SHLVL': '1', 22:17:34 INFO - 'TERM': 'linux', 22:17:34 INFO - 'TMOUT': '86400', 22:17:34 INFO - 'USER': 'cltbld', 22:17:34 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:34 INFO - '_': '/tools/buildbot/bin/python'} 22:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:17:35 INFO - Cleaning up... 22:17:35 INFO - Return code: 0 22:17:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:35 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 0x12fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15bc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', '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 22:17:35 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 22:17:35 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 22:17:35 INFO - Using env: {'DISPLAY': ':0', 22:17:35 INFO - 'HOME': '/home/cltbld', 22:17:35 INFO - 'LANG': 'en_US.UTF-8', 22:17:35 INFO - 'LANGUAGE': 'en_US:en', 22:17:35 INFO - 'LOGNAME': 'cltbld', 22:17:35 INFO - 'MAIL': '/var/mail/cltbld', 22:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:35 INFO - 'MOZ_NO_REMOTE': '1', 22:17:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:35 INFO - 'NO_EM_RESTART': '1', 22:17:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:35 INFO - 'PWD': '/builds/slave/test', 22:17:35 INFO - 'SHELL': '/bin/bash', 22:17:35 INFO - 'SHLVL': '1', 22:17:35 INFO - 'TERM': 'linux', 22:17:35 INFO - 'TMOUT': '86400', 22:17:35 INFO - 'USER': 'cltbld', 22:17:35 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:35 INFO - '_': '/tools/buildbot/bin/python'} 22:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:35 INFO - Downloading/unpacking blobuploader==1.2.4 22:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:37 INFO - Downloading blobuploader-1.2.4.tar.gz 22:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:17:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:17: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 22:17: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 22:17: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 22:17: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 22:17: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 22:17: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 22:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:17:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:17: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 22:17: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 22:17: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 22:17: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 22:17: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 22:17: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 22:17:37 INFO - Downloading docopt-0.6.1.tar.gz 22:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:17:37 INFO - Installing collected packages: blobuploader, requests, docopt 22:17:37 INFO - Running setup.py install for blobuploader 22:17:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:17:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:17:38 INFO - Running setup.py install for requests 22:17:38 INFO - Running setup.py install for docopt 22:17:38 INFO - Successfully installed blobuploader requests docopt 22:17:38 INFO - Cleaning up... 22:17:38 INFO - Return code: 0 22:17:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:38 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 0x12fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15bc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', '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 22:17:38 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 22:17:38 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 22:17:38 INFO - Using env: {'DISPLAY': ':0', 22:17:38 INFO - 'HOME': '/home/cltbld', 22:17:38 INFO - 'LANG': 'en_US.UTF-8', 22:17:38 INFO - 'LANGUAGE': 'en_US:en', 22:17:38 INFO - 'LOGNAME': 'cltbld', 22:17:38 INFO - 'MAIL': '/var/mail/cltbld', 22:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:38 INFO - 'MOZ_NO_REMOTE': '1', 22:17:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:38 INFO - 'NO_EM_RESTART': '1', 22:17:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:38 INFO - 'PWD': '/builds/slave/test', 22:17:38 INFO - 'SHELL': '/bin/bash', 22:17:38 INFO - 'SHLVL': '1', 22:17:38 INFO - 'TERM': 'linux', 22:17:38 INFO - 'TMOUT': '86400', 22:17:38 INFO - 'USER': 'cltbld', 22:17:38 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:38 INFO - '_': '/tools/buildbot/bin/python'} 22:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:38 INFO - Running setup.py (path:/tmp/pip-140wzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:38 INFO - Running setup.py (path:/tmp/pip-nmauDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:38 INFO - Running setup.py (path:/tmp/pip-VEJxMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:38 INFO - Running setup.py (path:/tmp/pip-Duc5vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:39 INFO - Running setup.py (path:/tmp/pip-83qjJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:39 INFO - Running setup.py (path:/tmp/pip-DxS2NA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:39 INFO - Running setup.py (path:/tmp/pip-eFkm7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:39 INFO - Running setup.py (path:/tmp/pip-Gmmoc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:39 INFO - Running setup.py (path:/tmp/pip-AWpHBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:39 INFO - Running setup.py (path:/tmp/pip-Dml3Po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:39 INFO - Running setup.py (path:/tmp/pip-V7SzsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:39 INFO - Running setup.py (path:/tmp/pip-JMfRyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:39 INFO - Running setup.py (path:/tmp/pip-jcRQn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:39 INFO - Running setup.py (path:/tmp/pip-wMSevu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:39 INFO - Running setup.py (path:/tmp/pip-21Puuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:39 INFO - Running setup.py (path:/tmp/pip-XNaP6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:39 INFO - Running setup.py (path:/tmp/pip-e4D8BQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:17:39 INFO - Running setup.py install for manifestparser 22:17:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Running setup.py install for mozcrash 22:17:40 INFO - Running setup.py install for mozdebug 22:17:40 INFO - Running setup.py install for mozdevice 22:17:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Running setup.py install for mozfile 22:17:40 INFO - Running setup.py install for mozhttpd 22:17:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Running setup.py install for mozinfo 22:17:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Running setup.py install for mozInstall 22:17:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Running setup.py install for mozleak 22:17:40 INFO - Running setup.py install for mozlog 22:17:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Running setup.py install for moznetwork 22:17:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Running setup.py install for mozprocess 22:17:41 INFO - Running setup.py install for mozprofile 22:17:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Running setup.py install for mozrunner 22:17:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Running setup.py install for mozscreenshot 22:17:41 INFO - Running setup.py install for moztest 22:17:41 INFO - Running setup.py install for mozversion 22:17:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:17:41 INFO - Cleaning up... 22:17:41 INFO - Return code: 0 22:17:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:41 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')]} 22:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:41 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')]} 22:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:41 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 0x12fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15bc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', '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 22:17:41 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 22:17:41 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 22:17:41 INFO - Using env: {'DISPLAY': ':0', 22:17:41 INFO - 'HOME': '/home/cltbld', 22:17:41 INFO - 'LANG': 'en_US.UTF-8', 22:17:41 INFO - 'LANGUAGE': 'en_US:en', 22:17:41 INFO - 'LOGNAME': 'cltbld', 22:17:41 INFO - 'MAIL': '/var/mail/cltbld', 22:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:41 INFO - 'MOZ_NO_REMOTE': '1', 22:17:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:41 INFO - 'NO_EM_RESTART': '1', 22:17:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:41 INFO - 'PWD': '/builds/slave/test', 22:17:41 INFO - 'SHELL': '/bin/bash', 22:17:41 INFO - 'SHLVL': '1', 22:17:41 INFO - 'TERM': 'linux', 22:17:41 INFO - 'TMOUT': '86400', 22:17:41 INFO - 'USER': 'cltbld', 22:17:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:41 INFO - '_': '/tools/buildbot/bin/python'} 22:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:42 INFO - Running setup.py (path:/tmp/pip-SpJ3Za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:42 INFO - Running setup.py (path:/tmp/pip-UmaNLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:42 INFO - Running setup.py (path:/tmp/pip-qfG2Vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:42 INFO - Running setup.py (path:/tmp/pip-bnkqM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:42 INFO - Running setup.py (path:/tmp/pip-Y8cL3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:42 INFO - Running setup.py (path:/tmp/pip-NyzLqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:42 INFO - Running setup.py (path:/tmp/pip-Cgbmkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:42 INFO - Running setup.py (path:/tmp/pip-OtSjz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:42 INFO - Running setup.py (path:/tmp/pip-eAfrLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:42 INFO - Running setup.py (path:/tmp/pip-jeHD7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:42 INFO - Running setup.py (path:/tmp/pip-Ji4B1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:42 INFO - Running setup.py (path:/tmp/pip-DbQ6ES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:42 INFO - Running setup.py (path:/tmp/pip-1xsucc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:42 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)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:42 INFO - Running setup.py (path:/tmp/pip-VH65JQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:43 INFO - Running setup.py (path:/tmp/pip-GlbyTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:43 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)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:43 INFO - Running setup.py (path:/tmp/pip-kxy_Gu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:43 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)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:43 INFO - Running setup.py (path:/tmp/pip-DO2SOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:43 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)) 22:17:43 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)) 22:17:43 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)) 22:17:43 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)) 22:17:43 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)) 22:17:43 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)) 22:17:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:17:43 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 22:17:43 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 22:17:43 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 22:17:43 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 22:17:43 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 22:17:43 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 22:17:45 INFO - Downloading blessings-1.6.tar.gz 22:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:17:45 INFO - Installing collected packages: blessings 22:17:45 INFO - Running setup.py install for blessings 22:17:45 INFO - Successfully installed blessings 22:17:45 INFO - Cleaning up... 22:17:45 INFO - Return code: 0 22:17:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:17:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:45 INFO - Reading from file tmpfile_stdout 22:17:45 INFO - Current package versions: 22:17:45 INFO - argparse == 1.2.1 22:17:45 INFO - blessings == 1.6 22:17:45 INFO - blobuploader == 1.2.4 22:17:45 INFO - docopt == 0.6.1 22:17:45 INFO - functools32 == 3.2.3-2 22:17:45 INFO - jsonschema == 2.5.1 22:17:45 INFO - manifestparser == 1.1 22:17:45 INFO - mozInstall == 1.12 22:17:45 INFO - mozcrash == 0.17 22:17:45 INFO - mozdebug == 0.1 22:17:45 INFO - mozdevice == 0.48 22:17:45 INFO - mozfile == 1.2 22:17:45 INFO - mozhttpd == 0.7 22:17:45 INFO - mozinfo == 0.9 22:17:45 INFO - mozleak == 0.1 22:17:45 INFO - mozlog == 3.3 22:17:45 INFO - moznetwork == 0.27 22:17:45 INFO - mozprocess == 0.23 22:17:45 INFO - mozprofile == 0.28 22:17:45 INFO - mozrunner == 6.12 22:17:45 INFO - mozscreenshot == 0.1 22:17:45 INFO - mozsystemmonitor == 0.3 22:17:45 INFO - moztest == 0.7 22:17:45 INFO - mozversion == 1.4 22:17:45 INFO - psutil == 3.1.1 22:17:45 INFO - requests == 1.2.3 22:17:45 INFO - wsgiref == 0.1.2 22:17:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17: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')]} 22:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17: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')]} 22:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:45 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 0x12fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15bc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', '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 22:17:45 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 22:17:45 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 22:17:45 INFO - Using env: {'DISPLAY': ':0', 22:17:45 INFO - 'HOME': '/home/cltbld', 22:17:45 INFO - 'LANG': 'en_US.UTF-8', 22:17:45 INFO - 'LANGUAGE': 'en_US:en', 22:17:45 INFO - 'LOGNAME': 'cltbld', 22:17:45 INFO - 'MAIL': '/var/mail/cltbld', 22:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:45 INFO - 'MOZ_NO_REMOTE': '1', 22:17:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:45 INFO - 'NO_EM_RESTART': '1', 22:17:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:45 INFO - 'PWD': '/builds/slave/test', 22:17:45 INFO - 'SHELL': '/bin/bash', 22:17:45 INFO - 'SHLVL': '1', 22:17:45 INFO - 'TERM': 'linux', 22:17:45 INFO - 'TMOUT': '86400', 22:17:45 INFO - 'USER': 'cltbld', 22:17:45 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:45 INFO - '_': '/tools/buildbot/bin/python'} 22:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:45 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)) 22:17:45 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)) 22:17:45 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)) 22:17:45 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)) 22:17:45 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)) 22:17:45 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)) 22:17:45 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)) 22:17:45 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)) 22:17:45 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)) 22:17:45 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)) 22:17:45 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)) 22:17:45 INFO - Cleaning up... 22:17:45 INFO - Return code: 0 22:17:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:17: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')]} 22:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17: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')]} 22:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15bc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', '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 22:17: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', 'jsonschema'] in /builds/slave/test/build 22:17: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 jsonschema 22:17:45 INFO - Using env: {'DISPLAY': ':0', 22:17:45 INFO - 'HOME': '/home/cltbld', 22:17:45 INFO - 'LANG': 'en_US.UTF-8', 22:17:45 INFO - 'LANGUAGE': 'en_US:en', 22:17:45 INFO - 'LOGNAME': 'cltbld', 22:17:45 INFO - 'MAIL': '/var/mail/cltbld', 22:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:45 INFO - 'MOZ_NO_REMOTE': '1', 22:17:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:45 INFO - 'NO_EM_RESTART': '1', 22:17:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:45 INFO - 'PWD': '/builds/slave/test', 22:17:45 INFO - 'SHELL': '/bin/bash', 22:17:45 INFO - 'SHLVL': '1', 22:17:45 INFO - 'TERM': 'linux', 22:17:45 INFO - 'TMOUT': '86400', 22:17:45 INFO - 'USER': 'cltbld', 22:17:45 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:45 INFO - '_': '/tools/buildbot/bin/python'} 22:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:17:46 INFO - Cleaning up... 22:17:46 INFO - Return code: 0 22:17:46 INFO - Running post-action listener: _resource_record_post_action 22:17:46 INFO - Running post-action listener: _start_resource_monitoring 22:17:46 INFO - Starting resource monitoring. 22:17:46 INFO - [mozharness: 2016-08-03 05:17:46.070038Z] Finished create-virtualenv step (success) 22:17:46 INFO - [mozharness: 2016-08-03 05:17:46.070565Z] Running install step. 22:17:46 INFO - Running pre-action listener: _resource_record_pre_action 22:17:46 INFO - Running main action method: install 22:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:46 INFO - Reading from file tmpfile_stdout 22:17:46 INFO - Detecting whether we're running mozinstall >=1.0... 22:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:17:46 INFO - Reading from file tmpfile_stdout 22:17:46 INFO - Output received: 22:17:46 INFO - Usage: mozinstall [options] installer 22:17:46 INFO - Options: 22:17:46 INFO - -h, --help show this help message and exit 22:17:46 INFO - -d DEST, --destination=DEST 22:17:46 INFO - Directory to install application into. [default: 22:17:46 INFO - "/builds/slave/test"] 22:17:46 INFO - --app=APP Application being installed. [default: firefox] 22:17:46 INFO - mkdir: /builds/slave/test/build/application 22:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:18:03 INFO - Reading from file tmpfile_stdout 22:18:03 INFO - Output received: 22:18:03 INFO - /builds/slave/test/build/application/firefox/firefox 22:18:03 INFO - Running post-action listener: _resource_record_post_action 22:18:03 INFO - [mozharness: 2016-08-03 05:18:03.346046Z] Finished install step (success) 22:18:03 INFO - [mozharness: 2016-08-03 05:18:03.346325Z] Running run-tests step. 22:18:03 INFO - Running pre-action listener: _resource_record_pre_action 22:18:03 INFO - Running main action method: run_tests 22:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:18:03 INFO - Python 2.7.3 22:18:03 INFO - Return code: 0 22:18:03 INFO - grabbing minidump binary from tooltool 22:18:03 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')]} 22:18:03 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 0x15ba3b0>, '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 0x15bb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15bb740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:18:03 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 22:18:03 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 22:18:03 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 22:18:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:18:03 INFO - Return code: 0 22:18:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:18:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:18:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:18:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:18:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:18:03 INFO - Using env: {'DISPLAY': ':0', 22:18:03 INFO - 'HOME': '/home/cltbld', 22:18:03 INFO - 'LANG': 'en_US.UTF-8', 22:18:03 INFO - 'LANGUAGE': 'en_US:en', 22:18:03 INFO - 'LOGNAME': 'cltbld', 22:18:03 INFO - 'MAIL': '/var/mail/cltbld', 22:18:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:18:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:03 INFO - 'MOZ_NO_REMOTE': '1', 22:18:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:18:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:03 INFO - 'NO_EM_RESTART': '1', 22:18:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:03 INFO - 'PWD': '/builds/slave/test', 22:18:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:18:03 INFO - 'SHELL': '/bin/bash', 22:18:03 INFO - 'SHLVL': '1', 22:18:03 INFO - 'TERM': 'linux', 22:18:03 INFO - 'TMOUT': '86400', 22:18:03 INFO - 'USER': 'cltbld', 22:18:03 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733', 22:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:03 INFO - '_': '/tools/buildbot/bin/python'} 22:18:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:18:03 INFO - mozversion application_buildid: 20160802193035 22:18:03 INFO - mozversion application_changeset: ae7b718d8afa96ccd8552b142d46f06f37c953b5 22:18:03 INFO - mozversion application_display_name: Nightly 22:18:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:18:03 INFO - mozversion application_name: Firefox 22:18:03 INFO - mozversion application_remotingname: firefox 22:18:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:18:03 INFO - mozversion application_vendor: Mozilla 22:18:03 INFO - mozversion application_version: 51.0a1 22:18:03 INFO - mozversion platform_buildid: 20160802193035 22:18:03 INFO - mozversion platform_changeset: ae7b718d8afa96ccd8552b142d46f06f37c953b5 22:18:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:18:03 INFO - mozversion platform_version: 51.0a1 22:18:03 INFO - using testdate: 1470201483 22:18:03 INFO - actual date: 1470201483 22:18:03 INFO - starting webserver on 'localhost:35144' 22:18:03 INFO - SUITE-START | Running 2 tests 22:18:03 INFO - TEST-START | dromaeo_css 22:18:03 INFO - operating with platform_type : linux_ 22:18:03 INFO - Initialising browser for dromaeo_css test... 22:18:03 INFO - TEST-INFO | started process 10947 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmDl0lV/profile http://localhost:35144/getInfo.html) 22:18:07 INFO - PROCESS | 10947 | __metrics Screen width/height:1600/1200 22:18:07 INFO - PROCESS | 10947 | colorDepth:24 22:18:07 INFO - PROCESS | 10947 | Browser inner width/height: 1024/697 22:18:07 INFO - PROCESS | 10947 | __metrics 22:18:08 INFO - PROCESS | 10947 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:18:08 INFO - PROCESS | 10947 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:18:10 INFO - TEST-INFO | 10947: exit 0 22:18:10 INFO - Browser initialized. 22:18:10 INFO - Running cycle 1/1 for dromaeo_css test... 22:18:10 INFO - TEST-INFO | started process 11030 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmDl0lV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:18:21 INFO - PROCESS | 11030 | RSS: Main: 156446720 22:18:21 INFO - PROCESS | 11030 | 22:19:13 INFO - PROCESS | 11030 | 1470201553375 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] 22:21:22 INFO - PROCESS | 11030 | Cycle 1(1): loaded http://localhost:35144/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35144/tests/dromaeo/cssquery-ext.html) 22:21:23 INFO - PROCESS | 11030 | RSS: Main: 274259968 22:21:23 INFO - PROCESS | 11030 | 22:24:26 INFO - PROCESS | 11030 | Cycle 1(1): loaded http://localhost:35144/tests/dromaeo/cssquery-ext.html (next: http://localhost:35144/tests/dromaeo/cssquery-jquery.html) 22:24:27 INFO - PROCESS | 11030 | RSS: Main: 240263168 22:24:27 INFO - PROCESS | 11030 | 22:27:29 INFO - PROCESS | 11030 | Cycle 1(1): loaded http://localhost:35144/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35144/tests/dromaeo/cssquery-mootools.html) 22:27:29 INFO - PROCESS | 11030 | RSS: Main: 216203264 22:27:29 INFO - PROCESS | 11030 | 22:30:31 INFO - PROCESS | 11030 | Cycle 1(1): loaded http://localhost:35144/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35144/tests/dromaeo/cssquery-prototype.html) 22:30:32 INFO - PROCESS | 11030 | RSS: Main: 183521280 22:30:32 INFO - PROCESS | 11030 | 22:33:35 INFO - PROCESS | 11030 | Cycle 1(1): loaded http://localhost:35144/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35144/tests/dromaeo/cssquery-yui.html) 22:33:35 INFO - PROCESS | 11030 | RSS: Main: 186970112 22:33:35 INFO - PROCESS | 11030 | 22:36:38 INFO - PROCESS | 11030 | Cycle 1(1): loaded http://localhost:35144/tests/dromaeo/cssquery-yui.html (next: http://localhost:35144/tests/dromaeo/cssquery-dojo.html) 22:36:38 INFO - PROCESS | 11030 | RSS: Main: 277876736 22:36:38 INFO - PROCESS | 11030 | 22:36:38 INFO - PROCESS | 11030 | __start_tp_report 22:36:38 INFO - PROCESS | 11030 | _x_x_mozilla_page_load 22:36:38 INFO - PROCESS | 11030 | _x_x_mozilla_page_load_details 22:36:38 INFO - PROCESS | 11030 | |i|pagename|runs| 22:36:38 INFO - PROCESS | 11030 | |0;dojo.html;3029.8485075746207;3051.3439610483742;3072.5698402223693;3082.5222090575967;3087.861046252918;5238.8690282742955;5282.709450980195;5302.549283310923;5362.410134885159;5417.539509141718;5286.074936886049;5325.227841962923;5341.225522299264;5356.1697936819755;5425.050616142169;4738.147133515974;4815.374001379816;4828.082664293791;4843.975780121112;5028.974855125719;4953.603711703073;4960.925586116193;4991.925121123186;5001.624878134155;5014.147594908874;4789.2816077588295;4801.855944321676;4819.108464933998;4865.635077369176;4917.3607431033915;4795.928061079087;4809.446913604946;4813.350197723297;4876.146674331978;4918.090153321611;4876.049170411771;4877.756112194412;4883.02339532095;4884.14527457697;4923.113839508887;5221.634485586011;5269.314989051399;5349.625526213166;5365.06111430502;5420.07858664028;5802.88394232113;5817.883642327172;5820.097884827858;5820.883582328372;5829.213056237396;5622;5834.737436815353;5841.0362290222065;5843.328017278025;5856.32652244993;5284.550813180885;5334.359876814807;5342.545883599938;5377.4084850666395;5414.187871819258;5883;5947.197128387699;5947.37552556984;5948;5948.078047902582;5487.149491828773;5684.090545512699;5688.943110568925;5689.203511508392;5689.943100569024;5646.435356464321;5659.405762394889;5700.572457065737;5737.540996720252;5786.797462088806;5659.943400566024;5696.287964004499;5699.430056994267;5702.116171993348;5709.0865461525955;5462.126059830488;5721.084626459747;5721.685307308138;5722.799702010409;5728.684922329228;4303.655707543452;4335.263005289045;4357.346398040319;4372.366006928977;4415.712978656377;3881.340172170738;3892.824822882977;3895.3183192941124;3924.6271604197555;3929.273084479323;5345.438728933484;5355.598330125256;5357.249985002103;5363.168708850135;5409.459054094559;5623.831285061455;5677.886442271131;5732.398098199629;5742.3396309424115;5799.3620701722775;5338.599605029638;5379.6234263601955;5390.164524498709;5400.945990540123;5400.946815371043;4904.558589726942;4921.286413470027;4928.162212423897;4931.4821943696115;4971.975140124277;5748.626339287933;5843.3864444233595;5909.349971503131;5916;5997.190379298739;5743.195952566728;5743.282089738783;5743.942560574341;5745.511631511368;5753;5889.057750759858;5936.376680448663;5943.583949123693;5948.167256584168;5948.702564871825;5535.418781027934;5618;5623.634463759842;5673.205751194919;5780.479756821738;5768.451997060272;5803.593748437569;5806.593538452268;5807.564432667651;5811.331696854916;5679.716014199356;5692.174634678031;5694.829155125187;5697.003024470784;5699.059655156852;5457.099578569491;5530.225768392348;5588.832335029955;5597.384287728428;5682.374938756816;5623.409541998195;5627.971860140673;5633.436656334334;5634.267545219259;5634.830955071355;5675.148727690879;5679.77280908759;5718.685472299064;5721.427857214246;5737.340205876378;5288.867778305573;5382.811601594082;5434.565234781129;5459.208414779755;5479.178123281539 22:36:38 INFO - PROCESS | 11030 | |1;ext.html;589.1516216648026;602.9336772954971;604.5526310530206;613.8987067133921;636.8057742388571;33919.83040084799;34707;36689.982050987186;36852.26295474089;44657.77671111648;43558;45785.31322030172;46412.30381544279;46479.30281045787;47983.28025079626;9080.455172689642;9216.124468175512;9224.907750922472;9230.86153707695;9235.861462078074;8302.501849888995;9058.682946096887;9148.130927561871;9149.634014639407;10719.946400268027;10794.568217271339;11161.162912781534;11224.326540407601;11225;11239.83140252897;11137.220394572383;11202.1598380121;11207.103431725523;11223.494942727597;11235.21353505255;10968.616098436516;11000.834987475195;11038.558457661684;11039.116870650329;11039.337639741561;44568;44915.32627010597;44927;44946;44956;3687.3362794696945;3895.104126051024;3994.121293315466;4057.97971010146;4079.714419990602;33538.1615459613;34057.148571285914;34322.82838585791;34463.31073378518;34974.300513989576;4324.372965919955;4365.236083685342;4378.912421751579;4387.341898715186;4519.615832654228;376284;376910.99999999726;377806;377821;378393;59065.40934590599;59243.70378148081;59449.811003780545;59767;59771.70114149401;62720;62853;62901.37098628955;63033.05450418247;63091.36908630855;15526.301316440562;15912.76130858038;15943.44197953066;15950.680986380208;15963.920180399024;12219.877801222052;12296.385180740926;12328.19866708661;12331.815022774666;12339.93830030844;12644.494220231089;14231.50189743354;16422.83577164237;16429.91785041067;16740.748888766677;6166.691665416712;6348.650824204621;6413.422791948747;6503.902441463382;6597.274299827015;995.4027583450016;1108.3349990005931;1114.5653195253858;1132.2753437799815;1144.5078616194949;33275.83362083174;33280.667193327754;33552.328953420794;33752.81865134709;34108.82945585256;1111.0278506306981;1116.5980247111197;1124.2748427264369;1128.142611615162;1166.416791604198;17282.308707651555;17471.82528174702;17589.20848561818;17623.823761762476;17764.9111754443;568780;571747;572227;572551;574151;60914;61119;61162.694186529676;61165;61232.38767612267;1261.8738126187309;1345.710672205461;1377.6969066805286;1380.6686395265265;1403.6701375176826;33152;34536.48195277023;34735;34885.476717848745;34927.47608785921;52207.477925220264;52744.472555273955;52760;52801.47198528119;52807.94384112373;403499;406744;407140;408524;408686;35713.821430892676;40233.195336092525;41821;42083.7895810519;42695.786521067195;48656;48867.75566122147;49205;49248.50751492583;50516.49483505118;41222.1755564893;41962.580374195866;42219.57780422157;43435.56564434315;43525;41563.592196911006;41667.37498937579;42282;42627.57372426236;42820.57179428291;41402.585974141075;41525.169496610506;41998.1600367997;42315.3652695216;42497.78751106225;35864;37830.62169378271;38254;38616.87495965626;38791.03022424462;28573.571396429485;28592.857035715522;28760.424791504473;28778.42443151167;29111.85444072766 22:36:38 INFO - PROCESS | 11030 | |2;jquery.html;382.885134459662;384.6691845013288;391.53016380343587;393.9763614183149;417.14485305124487;14273.12775330397;17743.55641108973;18477.53806154845;20285.39143825686;20345.59308813823;16398.26207820648;17534.736978945326;18375.35686250978;18971.43085707431;19176;5772.855678608026;5929.169916211734;5940.61386009908;5941.168236446901;5963.701814909237;5843.357230704619;5878.941210587904;5882.970585147068;5885.058390657496;5886.058230683092;5783.6240644358195;6169.383061693839;6422.710978006001;6452.257990331125;6562.540622156451;6567.310432404576;6599.769008084718;6727.899081513781;6800.285969973131;6845.863082738367;5880.794172203952;6069.757209711606;6075.787347442818;6078.240219972504;6082.057281121433;23366.182183623492;23474.70889101099;23490.412739681473;24049.879750601136;24186.63720044183;2204.6472564389783;2278.9886050569808;2331.2773040357542;2341.082428501329;2838.801283910127;10208.693739187762;10265.794684106351;10353.482325883751;10434.843477347846;10854.403007834566;1965.5380985468403;1994.1325523397225;2022.5853699991615;2028.4523178741656;2067.65883629201;84617;86844.56577717197;87592.56203719004;88961;92397.07602923951;11219.214654974074;11460.770784584261;11463.254888432226;11480.77038459226;11745.823812642817;16352.264148113363;16358.018518888905;16377.67244655124;16409.261583228785;16421.91789041071;7753.922460775433;7922.7227047053075;8101.554414507141;8268.09051004389;8271.090180080186;7499.587522686195;7564.357029652541;7628.237176282328;7751.7674469766;7797.883031754528;4389.670774691911;4710.434747830213;4852.636052296021;4873.122837889214;5095.770690318945;1869.4765465669634;2001.599680063993;2029.8287020173684;2048.7336646235894;2069.9793002069787;885.6324625280565;953.40780895904;988.5551501824208;1032.4528000159928;1045.6340280901622;19855;20392;20639.69040464394;20669.379918602466;20737.170513179604;993.5677980078689;995.8854731705802;1015.421209910344;1038.7818558102883;1046.8691413573304;10837.620683276047;11427.200095993367;11434.142439317084;11505.769884602261;11547.538098476134;179364;453641;478176;484153;484231;90962.54518727497;91298.54350728204;91775.5411222953;92069.5396523013;92476;980.7302991677345;996.3361186818031;1026.5637104230673;1034.1674951663833;1037.2479952034714;17086.829131708524;17984.2806287747;18221;18335;18623.81376186275;40965.59034409618;41155.7942210287;41169;41630.167396652505;43114.353284701385;53256.73371633117;54502.72748636232;54556;56514.434855650914;58698.119528207964;8496.405251632326;8878.378513503993;9075.954620226856;9152;9160.679376222068;10059.547320370748;11998.58004969822;13370.799438008244;13473.797893031808;14089.309282886119;11657.708557285796;11851.288922664668;11859.40702964831;12009.459574319002;12196.085293602839;11217.49521271561;11713.062954963752;11714.121440891797;11731.88268117308;11787.882121178678;11283.774324513628;12061.396930153633;12086.153969222421;12261.570845020382;12303.614049599046;8526.957365213135;8587.699430519902;8800.33997450181;9109.908900910907;9256.49089300095;8563.486190828571;10936.507857146607;11209;11644.475998579916;11718 22:36:38 INFO - PROCESS | 11030 | |3;mootools.html;1321.927293998831;1464.363002094089;1523.3297349166367;1640.9261583228754;1783.4025601423523;8181.795455113618;8500.787480312987;8592.269657079149;8818.559072046388;8829.735107946772;8638.784030399243;8747.081556436562;8758.605862736176;8769.47383157011;8776.517291548962;2400.363903565552;2445.021991203524;2447.1679628926163;2447.3759191406257;2448.2655203438985;2347.5891718949115;2383.6067048936993;2395.317334559642;2397.0891061396733;2398.3164798032562;2380.833341666084;2390.605550084234;2396.880155992202;2422.2127808462233;2423.3578101803037;2336.960052776515;2340.9648855267183;2342.906283748648;2346.272655476799;2386.3198988288254;2301.5396920615776;2322.210448447519;2322.8606283623035;2324.3491822289784;2327.8486898351553;8665;8718.564071796385;8827.77930551735;8829.911700882973;8852.8672069919;5186.377634683824;5414.7834086636485;5423.023855705971;5427.131658934591;5427.375851777057;3429.639887811769;3463.8787642432644;3508.7894726316504;3522.84147213376;3530.064532898784;1144.4621028116771;1148.4200478758173;1155.5493357590547;1160.1414952934851;1183.9171258011943;12106.697332566668;12126.393680316038;12171.208871423241;12216.694582635417;12216.877831221664;4464.419625448671;4575.565321294472;4743.762811859393;4747.501512341223;4771.928421073687;9769.706908792748;9771.31600787938;9786;9786.314957952874;9797;5370.3287089113865;5373.247745315659;5384.0847056000575;5390.19147127934;5390.353157621033;4326.783660816946;4708.293755936568;4860.246661767361;4899.363082799213;5013.147764879979;3855.51806024247;3857.1128640412303;3964.108075683006;3990.7206495545033;4029.979850100731;1179.5281887245171;1183.2900259844196;1186.4127257007835;1196.342011893455;1208.1965492947268;938.8169306985209;944.6693657220055;950.12588418655;955.8805149356331;986.9654562090292;5569.554435645138;5670.206171136044;5687.829365119053;5750.654960702288;5839.795607153814;454.4069988664758;458.0998338265258;462.50973967594456;466.91128685549637;468.86402943146106;4115.9176816464105;4179.477565304337;4198.6221240088535;4287.849925252601;4290.935635965463;10762.515686794113;11102.500387482422;11149.944250278697;11161.386123763272;11168.944155279172;10901.83647245292;10947.890521094847;11018.898110188917;11039.889601104047;11085.778284434266;7830.530168189927;8215.219554142346;8267.958660206781;8303.543305118277;8335.87465692136;5995;6003.339632640494;6128.142060111499;6161.476274516716;6199.225096862892;9664.420134791935;9891.851622225528;9930.404175749478;10172.033656802592;10174.236932229964;10073;10312.896871031193;10318.845217321896;10336.844947325946;10464.00591943764;7071.36357727807;7618.123915749759;7806.531608103532;7852.646630901738;7860.0567931848545;8643.697470588499;8739.257163141205;8747.475151490931;8759.649614015369;8767.386282960386;7723.7682869514;7725.690972361042;7863.646135923783;7943.642536086006;8019.318357939641;7445;7554.508956917783;7569.47013709035;7575.924240757522;7586.772396828104;7109.857802844018;7216.675249613675;7218.314260145278;7230.6384680766805;7230.783076507713;4872.780724867319;4989.476105008922;5039.26930595069;5040.420351659606;5166.509181627739;5099.796008159632;5253.632245742761;5304.283921670525;5345.54562862161;5352.892942141213 22:36:38 INFO - PROCESS | 11030 | |4;prototype.html;317.13896770268724;318.10136364769517;325.95762550868375;328.7238719475643;330.38548300161693;5207.880305734267;5221.425643179256;5227.372715274168;5241.187615919539;5255.343082114736;5192.636515443921;5201.895962080756;5202.609804264685;5216.113260745673;5233.21501774733;3898.356771132767;3948.756141815319;3994.9800250998705;4018.1361007383407;4024.859129930453;4018.4575082363945;4091.018155642654;4091.079507110906;4099.364598487239;4116.320807066831;4059.289624315748;4059.411385349137;4068.5524592294823;4072.6538244249273;4075.694322925792;4063.5618628822617;4070.8778736637946;4079.4900637420324;4079.9796001019804;4080.9387859182134;3392.4402473591826;3456.343294773985;3543.291341731664;3603.855845766166;3626.691731202851;5011.148104822152;5011.774470148852;5012.398512178525;5020.874478138076;5035.84892453227;4703.2004559225015;4703.6829687687505;4705.788239529195;4705.788239529229;4714.363560919301;5299.973500132513;5389;5409.648372855751;5416.485433883814;5430.511253987121;3981.3430783920617;3984.7609143451195;3985.329829014567;3988.7407318524492;3993.520777506689;5373.6775793452125;5411.645934018675;5630;5631;5635.154726790973;4011.1777085697363;4053.574374690615;4060.675145988314;4109.650679692199;4110.753354798721;4146.91706165875;4152.736758466643;4161.97919010403;4167.416561681367;4169.332906734969;4527.275635898306;4688.460827004938;4696.618434726991;4708.8116475341285;4711.528847115261;5421.728913554307;5457.61796674229;5462.617616766788;5463.453654634584;5470.589705772083;1417.844037155912;1432.6776475292975;1434.956951291463;1436.5977526292654;1439.5465428390023;858.8539948208819;915.8689019061454;924.4222361024359;930.9813803724023;934.8223837470858;4660.277656963176;4675.602573781199;4683.6721429500285;4692.976535117303;4724.771559394582;4950;4961.925571116436;4967.105920934194;4968.22992436173;4992.553648712105;4680.321353403738;4696.718196908197;4697.553732395417;4703;4940.7035577865445;2948.395578906319;2969.6436427628823;2976.7320941115404;2996;3019.637643482796;5797.130430435468;5812.912806307909;5820.592558520862;5858.121281807677;5877.147813567094;5508.338999320107;5717.399673034354;5737.254156959652;5737.3688894221605;5751.683657398883;5755.942440575541;5794.536437085023;5812.622179558315;5829.970850145807;5837.182794408701;5414.431484694051;5633.549316054624;5638.774449021993;5643.689597072201;5655.066913959151;5549.472800083986;5553.861153471195;5555.666660000413;5563.443655634404;5581.5813813963305;5546.944530554642;5670.858228544154;5679.716014199191;5704.1443783432815;5814.970925145347;3875.3411919974237;3899.824507897095;3989.2221016902586;4009.4787677601726;4014.6186112319283;5605.467480589337;5609.49514543693;5612.887742245214;5618.185363122406;5618.763871948165;5281.867953301198;5487.996881138435;5533.833984980457;5536.584756143225;5537.612367134262;5465.6447330923365;5470.370907345706;5499.047838017446;5499.285092937893;5503.477169668875;5327.573794096541;5400.729963501731;5477.452254774491;5507.9449205507435;5514.696691681996;5561.5272701820795;5566.721663916869;5576.302962129734;5578.665280083045;5585.385607583244;5418.810341638024;5424.457554244702;5437.211604317431;5439.960014994377;5439.972800136133 22:36:38 INFO - PROCESS | 11030 | |5;yui.html;721.7978965889549;747.4618274842112;774.4733581164808;788.8777239527875;818.2799136759658;14155.15069095855;14972.475963341285;15004.699906001873;15014.774778378305;15076.020058696191;15332.15673137977;15486.69026619467;15489.535313940572;15492.070475771461;15493.84506154938;643.4562794438692;770.1566784371105;775.8603451378749;787.358302983067;803.6544285957021;808.9110197878229;815.1074573342211;815.3680897304577;815.4617952151581;815.7878951472601;857.2584714222215;862.374778285741;866.5320726807516;870.0603348383762;870.1255238485313;674.5177198303188;679.2663922963212;681.4752640466862;820.4871955028108;857.1728282207669;610.6763415389851;622.2968046107915;622.4366947912147;622.498888394842;623.7754408412968;14874.553763387008;14881.255937203205;14887.404503819835;14895;14901;13637.113587616872;13696.93151534246;14084.859151408456;14146.71706565863;14221.21783301918;12341.382930853422;12559.623211303675;12655.620331390073;12794.232346059172;12812.679683008;4287.8070486828165;4349.456317960255;4358.8910277243;4421.646268298528;4588.105319462706;83645.16354836496;84074.15925840786;84109.57945210235;84577.57711211467;87699;11064.907609617003;11121.888781112248;11135.33188008722;11141.888581114246;11311.038561722182;21424.892875535836;21454.892725536272;21459;21459.8927005364;21468.141274349164;7775.844483110306;7914.129445761077;7984.20157984197;8046.195380462024;8241.670333186725;4423.159599676052;4574.245249533857;4601.493835678073;4654.743989080565;4705.741184234831;3316.8673253069605;3408.4716868884866;3442.397580423416;3445.672661097192;3800.1449673823167;639.5043841023152;667.549404152205;667.9131712877295;669.3808227389645;684.3191024930227;593.4569868570296;599.4814485470037;695.6139342664869;736.9042024536776;745.6532712288812;9603.137177036971;9781.217502599917;9965.651202207888;10074.798504029879;10264.486775661187;638.9265234497999;668.6556423441931;708.5005071424633;741.8405042915691;747.4937999930145;4552.6130278926;4909.563285867269;4932.7533623319405;4952.62855285855;4969.0807200668;107762.46118769357;116361.41819291019;119698;122433;122467.3876630629;84641;89198;90916.54541727249;111978.44010779895;112267.43866280779;77326.61336693393;87146;87967;89562.55218723735;90841.09158908458;9756;9818.405663280986;9865.506724663595;10060.69817905464;10204.285700000928;49063.50936490589;50820.23769643384;50992.49007509877;52522.73738631282;53428;73278.63360683163;74735;76925;77143.55403125074;78566.6071669638;11732.354720490455;12116.75766484683;13083.476660933457;13209.471621135048;13534.729305414034;14449.422023118957;15395.769063463833;15510.767338490157;15736.606584835014;15767.921160394124;14548.636284092983;14846.480373186887;15280.159591222146;15325;15709.371625134867;13953.720925581634;14141.151530908179;14335.441779051067;14551;14600.926995364955;13957.790633140714;14075;14094.436222550983;14390.640233994234;14556.199409032604;11729.824052639047;11756.118291128028;12059.550382652837;12417.19288246261;12492.31292278897;10480.790384192122;10611.363318200933;11933.164678472423;12009.579664712042;12112.878871211527 22:36:38 INFO - PROCESS | 11030 | __end_tp_report 22:36:38 INFO - PROCESS | 11030 | __start_cc_report 22:36:38 INFO - PROCESS | 11030 | _x_x_mozilla_cycle_collect,1105 22:36:38 INFO - PROCESS | 11030 | __end_cc_report 22:36:38 INFO - PROCESS | 11030 | __startTimestamp1470202598589__endTimestamp 22:36:38 INFO - PROCESS | 11030 | 22:36:38 INFO - PROCESS | 11030 | ------- Summary: start ------- 22:36:38 INFO - PROCESS | 11030 | Number of tests: 6 22:36:38 INFO - PROCESS | 11030 | 22:36:38 INFO - PROCESS | 11030 | [#0] dojo.html Cycles:165 Average:5326.02 Median:5487.15 stddev:615.82 (11.2%) stddev-sans-first:590.77 22:36:38 INFO - PROCESS | 11030 | Values: 3029.8 3051.3 3072.6 3082.5 3087.9 5238.9 5282.7 5302.5 5362.4 5417.5 5286.1 5325.2 5341.2 5356.2 5425.1 4738.1 4815.4 4828.1 4844.0 5029.0 4953.6 4960.9 4991.9 5001.6 5014.1 4789.3 4801.9 4819.1 4865.6 4917.4 4795.9 4809.4 4813.4 4876.1 4918.1 4876.0 4877.8 4883.0 4884.1 4923.1 5221.6 5269.3 5349.6 5365.1 5420.1 5802.9 5817.9 5820.1 5820.9 5829.2 5622.0 5834.7 5841.0 5843.3 5856.3 5284.6 5334.4 5342.5 5377.4 5414.2 5883.0 5947.2 5947.4 5948.0 5948.1 5487.1 5684.1 5688.9 5689.2 5689.9 5646.4 5659.4 5700.6 5737.5 5786.8 5659.9 5696.3 5699.4 5702.1 5709.1 5462.1 5721.1 5721.7 5722.8 5728.7 4303.7 4335.3 4357.3 4372.4 4415.7 3881.3 3892.8 3895.3 3924.6 3929.3 5345.4 5355.6 5357.2 5363.2 5409.5 5623.8 5677.9 5732.4 5742.3 5799.4 5338.6 5379.6 5390.2 5400.9 5400.9 4904.6 4921.3 4928.2 4931.5 4972.0 5748.6 5843.4 5909.3 5916.0 5997.2 5743.2 5743.3 5743.9 5745.5 5753.0 5889.1 5936.4 5943.6 5948.2 5948.7 5535.4 5618.0 5623.6 5673.2 5780.5 5768.5 5803.6 5806.6 5807.6 5811.3 5679.7 5692.2 5694.8 5697.0 5699.1 5457.1 5530.2 5588.8 5597.4 5682.4 5623.4 5628.0 5633.4 5634.3 5634.8 5675.1 5679.8 5718.7 5721.4 5737.3 5288.9 5382.8 5434.6 5459.2 5479.2 22:36:38 INFO - PROCESS | 11030 | 22:36:38 INFO - PROCESS | 11030 | [#1] ext.html Cycles:180 Average:62161.51 Median:205400.99 stddev:121937.57 (59.4%) stddev-sans-first:122192.00 22:36:38 INFO - PROCESS | 11030 | Values: 589.2 602.9 604.6 613.9 636.8 33919.8 34707.0 36690.0 36852.3 44657.8 43558.0 45785.3 46412.3 46479.3 47983.3 9080.5 9216.1 9224.9 9230.9 9235.9 8302.5 9058.7 9148.1 9149.6 10719.9 10794.6 11161.2 11224.3 11225.0 11239.8 11137.2 11202.2 11207.1 11223.5 11235.2 10968.6 11000.8 11038.6 11039.1 11039.3 44568.0 44915.3 44927.0 44946.0 44956.0 3687.3 3895.1 3994.1 4058.0 4079.7 33538.2 34057.1 34322.8 34463.3 34974.3 4324.4 4365.2 4378.9 4387.3 4519.6 376284.0 376911.0 377806.0 377821.0 378393.0 59065.4 59243.7 59449.8 59767.0 59771.7 62720.0 62853.0 62901.4 63033.1 63091.4 15526.3 15912.8 15943.4 15950.7 15963.9 12219.9 12296.4 12328.2 12331.8 12339.9 12644.5 14231.5 16422.8 16429.9 16740.7 6166.7 6348.7 6413.4 6503.9 6597.3 995.4 1108.3 1114.6 1132.3 1144.5 33275.8 33280.7 33552.3 33752.8 34108.8 1111.0 1116.6 1124.3 1128.1 1166.4 17282.3 17471.8 17589.2 17623.8 17764.9 568780.0 571747.0 572227.0 572551.0 574151.0 60914.0 61119.0 61162.7 61165.0 61232.4 1261.9 1345.7 1377.7 1380.7 1403.7 33152.0 34536.5 34735.0 34885.5 34927.5 52207.5 52744.5 52760.0 52801.5 52807.9 403499.0 406744.0 407140.0 408524.0 408686.0 35713.8 40233.2 41821.0 42083.8 42695.8 48656.0 48867.8 49205.0 49248.5 50516.5 41222.2 41962.6 42219.6 43435.6 43525.0 41563.6 41667.4 42282.0 42627.6 42820.6 41402.6 41525.2 41998.2 42315.4 42497.8 35864.0 37830.6 38254.0 38616.9 38791.0 28573.6 28592.9 28760.4 28778.4 29111.9 22:36:38 INFO - PROCESS | 11030 | 22:36:38 INFO - PROCESS | 11030 | [#2] jquery.html Cycles:180 Average:27191.52 Median:24118.26 stddev:71944.10 (298.3%) stddev-sans-first:72117.76 22:36:38 INFO - PROCESS | 11030 | Values: 382.9 384.7 391.5 394.0 417.1 14273.1 17743.6 18477.5 20285.4 20345.6 16398.3 17534.7 18375.4 18971.4 19176.0 5772.9 5929.2 5940.6 5941.2 5963.7 5843.4 5878.9 5883.0 5885.1 5886.1 5783.6 6169.4 6422.7 6452.3 6562.5 6567.3 6599.8 6727.9 6800.3 6845.9 5880.8 6069.8 6075.8 6078.2 6082.1 23366.2 23474.7 23490.4 24049.9 24186.6 2204.6 2279.0 2331.3 2341.1 2838.8 10208.7 10265.8 10353.5 10434.8 10854.4 1965.5 1994.1 2022.6 2028.5 2067.7 84617.0 86844.6 87592.6 88961.0 92397.1 11219.2 11460.8 11463.3 11480.8 11745.8 16352.3 16358.0 16377.7 16409.3 16421.9 7753.9 7922.7 8101.6 8268.1 8271.1 7499.6 7564.4 7628.2 7751.8 7797.9 4389.7 4710.4 4852.6 4873.1 5095.8 1869.5 2001.6 2029.8 2048.7 2070.0 885.6 953.4 988.6 1032.5 1045.6 19855.0 20392.0 20639.7 20669.4 20737.2 993.6 995.9 1015.4 1038.8 1046.9 10837.6 11427.2 11434.1 11505.8 11547.5 179364.0 453641.0 478176.0 484153.0 484231.0 90962.5 91298.5 91775.5 92069.5 92476.0 980.7 996.3 1026.6 1034.2 1037.2 17086.8 17984.3 18221.0 18335.0 18623.8 40965.6 41155.8 41169.0 41630.2 43114.4 53256.7 54502.7 54556.0 56514.4 58698.1 8496.4 8878.4 9076.0 9152.0 9160.7 10059.5 11998.6 13370.8 13473.8 14089.3 11657.7 11851.3 11859.4 12009.5 12196.1 11217.5 11713.1 11714.1 11731.9 11787.9 11283.8 12061.4 12086.2 12261.6 12303.6 8527.0 8587.7 8800.3 9109.9 9256.5 8563.5 10936.5 11209.0 11644.5 11718.0 22:36:38 INFO - PROCESS | 11030 | 22:36:38 INFO - PROCESS | 11030 | [#3] mootools.html Cycles:180 Average:5796.69 Median:4886.07 stddev:3291.43 (67.4%) stddev-sans-first:3283.48 22:36:38 INFO - PROCESS | 11030 | Values: 1321.9 1464.4 1523.3 1640.9 1783.4 8181.8 8500.8 8592.3 8818.6 8829.7 8638.8 8747.1 8758.6 8769.5 8776.5 2400.4 2445.0 2447.2 2447.4 2448.3 2347.6 2383.6 2395.3 2397.1 2398.3 2380.8 2390.6 2396.9 2422.2 2423.4 2337.0 2341.0 2342.9 2346.3 2386.3 2301.5 2322.2 2322.9 2324.3 2327.8 8665.0 8718.6 8827.8 8829.9 8852.9 5186.4 5414.8 5423.0 5427.1 5427.4 3429.6 3463.9 3508.8 3522.8 3530.1 1144.5 1148.4 1155.5 1160.1 1183.9 12106.7 12126.4 12171.2 12216.7 12216.9 4464.4 4575.6 4743.8 4747.5 4771.9 9769.7 9771.3 9786.0 9786.3 9797.0 5370.3 5373.2 5384.1 5390.2 5390.4 4326.8 4708.3 4860.2 4899.4 5013.1 3855.5 3857.1 3964.1 3990.7 4030.0 1179.5 1183.3 1186.4 1196.3 1208.2 938.8 944.7 950.1 955.9 987.0 5569.6 5670.2 5687.8 5750.7 5839.8 454.4 458.1 462.5 466.9 468.9 4115.9 4179.5 4198.6 4287.8 4290.9 10762.5 11102.5 11149.9 11161.4 11168.9 10901.8 10947.9 11018.9 11039.9 11085.8 7830.5 8215.2 8268.0 8303.5 8335.9 5995.0 6003.3 6128.1 6161.5 6199.2 9664.4 9891.9 9930.4 10172.0 10174.2 10073.0 10312.9 10318.8 10336.8 10464.0 7071.4 7618.1 7806.5 7852.6 7860.1 8643.7 8739.3 8747.5 8759.6 8767.4 7723.8 7725.7 7863.6 7943.6 8019.3 7445.0 7554.5 7569.5 7575.9 7586.8 7109.9 7216.7 7218.3 7230.6 7230.8 4872.8 4989.5 5039.3 5040.4 5166.5 5099.8 5253.6 5304.3 5345.5 5352.9 22:36:38 INFO - PROCESS | 11030 | 22:36:38 INFO - PROCESS | 11030 | [#4] prototype.html Cycles:180 Average:4565.59 Median:5197.27 stddev:1334.54 (25.7%) stddev-sans-first:1299.63 22:36:38 INFO - PROCESS | 11030 | Values: 317.1 318.1 326.0 328.7 330.4 5207.9 5221.4 5227.4 5241.2 5255.3 5192.6 5201.9 5202.6 5216.1 5233.2 3898.4 3948.8 3995.0 4018.1 4024.9 4018.5 4091.0 4091.1 4099.4 4116.3 4059.3 4059.4 4068.6 4072.7 4075.7 4063.6 4070.9 4079.5 4080.0 4080.9 3392.4 3456.3 3543.3 3603.9 3626.7 5011.1 5011.8 5012.4 5020.9 5035.8 4703.2 4703.7 4705.8 4705.8 4714.4 5300.0 5389.0 5409.6 5416.5 5430.5 3981.3 3984.8 3985.3 3988.7 3993.5 5373.7 5411.6 5630.0 5631.0 5635.2 4011.2 4053.6 4060.7 4109.7 4110.8 4146.9 4152.7 4162.0 4167.4 4169.3 4527.3 4688.5 4696.6 4708.8 4711.5 5421.7 5457.6 5462.6 5463.5 5470.6 1417.8 1432.7 1435.0 1436.6 1439.5 858.9 915.9 924.4 931.0 934.8 4660.3 4675.6 4683.7 4693.0 4724.8 4950.0 4961.9 4967.1 4968.2 4992.6 4680.3 4696.7 4697.6 4703.0 4940.7 2948.4 2969.6 2976.7 2996.0 3019.6 5797.1 5812.9 5820.6 5858.1 5877.1 5508.3 5717.4 5737.3 5737.4 5751.7 5755.9 5794.5 5812.6 5830.0 5837.2 5414.4 5633.5 5638.8 5643.7 5655.1 5549.5 5553.9 5555.7 5563.4 5581.6 5546.9 5670.9 5679.7 5704.1 5815.0 3875.3 3899.8 3989.2 4009.5 4014.6 5605.5 5609.5 5612.9 5618.2 5618.8 5281.9 5488.0 5533.8 5536.6 5537.6 5465.6 5470.4 5499.0 5499.3 5503.5 5327.6 5400.7 5477.5 5507.9 5514.7 5561.5 5566.7 5576.3 5578.7 5585.4 5418.8 5424.5 5437.2 5440.0 5440.0 22:36:38 INFO - PROCESS | 11030 | 22:36:38 INFO - PROCESS | 11030 | [#5] yui.html Cycles:180 Average:21276.13 Median:4487.89 stddev:30696.50 (684.0%) stddev-sans-first:30743.81 22:36:38 INFO - PROCESS | 11030 | Values: 721.8 747.5 774.5 788.9 818.3 14155.2 14972.5 15004.7 15014.8 15076.0 15332.2 15486.7 15489.5 15492.1 15493.8 643.5 770.2 775.9 787.4 803.7 808.9 815.1 815.4 815.5 815.8 857.3 862.4 866.5 870.1 870.1 674.5 679.3 681.5 820.5 857.2 610.7 622.3 622.4 622.5 623.8 14874.6 14881.3 14887.4 14895.0 14901.0 13637.1 13696.9 14084.9 14146.7 14221.2 12341.4 12559.6 12655.6 12794.2 12812.7 4287.8 4349.5 4358.9 4421.6 4588.1 83645.2 84074.2 84109.6 84577.6 87699.0 11064.9 11121.9 11135.3 11141.9 11311.0 21424.9 21454.9 21459.0 21459.9 21468.1 7775.8 7914.1 7984.2 8046.2 8241.7 4423.2 4574.2 4601.5 4654.7 4705.7 3316.9 3408.5 3442.4 3445.7 3800.1 639.5 667.5 667.9 669.4 684.3 593.5 599.5 695.6 736.9 745.7 9603.1 9781.2 9965.7 10074.8 10264.5 638.9 668.7 708.5 741.8 747.5 4552.6 4909.6 4932.8 4952.6 4969.1 107762.5 116361.4 119698.0 122433.0 122467.4 84641.0 89198.0 90916.5 111978.4 112267.4 77326.6 87146.0 87967.0 89562.6 90841.1 9756.0 9818.4 9865.5 10060.7 10204.3 49063.5 50820.2 50992.5 52522.7 53428.0 73278.6 74735.0 76925.0 77143.6 78566.6 11732.4 12116.8 13083.5 13209.5 13534.7 14449.4 15395.8 15510.8 15736.6 15767.9 14548.6 14846.5 15280.2 15325.0 15709.4 13953.7 14141.2 14335.4 14551.0 14600.9 13957.8 14075.0 14094.4 14390.6 14556.2 11729.8 11756.1 12059.6 12417.2 12492.3 10480.8 10611.4 11933.2 12009.6 12112.9 22:36:38 INFO - PROCESS | 11030 | -------- Summary: end -------- 22:36:38 INFO - PROCESS | 11030 | 22:36:39 INFO - TEST-INFO | 11030: exit 0 22:36:39 INFO - TEST-OK | dromaeo_css | took 1115737ms 22:36:39 INFO - TEST-START | kraken 22:36:39 INFO - operating with platform_type : linux_ 22:36:39 INFO - Initialising browser for kraken test... 22:36:39 INFO - TEST-INFO | started process 11225 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeGBGco/profile http://localhost:35144/getInfo.html) 22:36:43 INFO - PROCESS | 11225 | __metrics Screen width/height:1600/1200 22:36:43 INFO - PROCESS | 11225 | colorDepth:24 22:36:43 INFO - PROCESS | 11225 | Browser inner width/height: 1024/697 22:36:43 INFO - PROCESS | 11225 | __metrics 22:36:44 INFO - PROCESS | 11225 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:36:44 INFO - PROCESS | 11225 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:36:45 INFO - PROCESS | 11225 | console.error: PushService: 22:36:45 INFO - PROCESS | 11225 | stateChangeProcessEnqueue: Error transitioning state 22:36:45 INFO - PROCESS | 11225 | UnknownError 22:36:45 INFO - TEST-INFO | 11225: exit 0 22:36:45 INFO - Browser initialized. 22:36:45 INFO - Running cycle 1/1 for kraken test... 22:36:45 INFO - TEST-INFO | started process 11306 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeGBGco/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:36:57 INFO - PROCESS | 11306 | RSS: Main: 155709440 22:36:57 INFO - PROCESS | 11306 | 22:37:00 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35144/tests/kraken/driver.html?testName=audio-beat-detection) 22:37:00 INFO - PROCESS | 11306 | RSS: Main: 212557824 22:37:00 INFO - PROCESS | 11306 | 22:37:03 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35144/tests/kraken/driver.html?testName=audio-dft) 22:37:04 INFO - PROCESS | 11306 | RSS: Main: 191926272 22:37:04 INFO - PROCESS | 11306 | 22:37:07 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35144/tests/kraken/driver.html?testName=audio-fft) 22:37:07 INFO - PROCESS | 11306 | RSS: Main: 268783616 22:37:07 INFO - PROCESS | 11306 | 22:37:10 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35144/tests/kraken/driver.html?testName=audio-oscillator) 22:37:10 INFO - PROCESS | 11306 | RSS: Main: 178618368 22:37:10 INFO - PROCESS | 11306 | 22:37:12 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35144/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:37:13 INFO - PROCESS | 11306 | RSS: Main: 183922688 22:37:13 INFO - PROCESS | 11306 | 22:37:16 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35144/tests/kraken/driver.html?testName=imaging-darkroom) 22:37:17 INFO - PROCESS | 11306 | RSS: Main: 219140096 22:37:17 INFO - PROCESS | 11306 | 22:37:20 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35144/tests/kraken/driver.html?testName=imaging-desaturate) 22:37:20 INFO - PROCESS | 11306 | RSS: Main: 219869184 22:37:20 INFO - PROCESS | 11306 | 22:37:23 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35144/tests/kraken/driver.html?testName=json-parse-financial) 22:37:24 INFO - PROCESS | 11306 | RSS: Main: 219381760 22:37:24 INFO - PROCESS | 11306 | 22:37:25 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35144/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:37:26 INFO - PROCESS | 11306 | RSS: Main: 183627776 22:37:26 INFO - PROCESS | 11306 | 22:37:28 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35144/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:37:28 INFO - PROCESS | 11306 | RSS: Main: 189681664 22:37:28 INFO - PROCESS | 11306 | 22:37:30 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35144/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:37:31 INFO - PROCESS | 11306 | RSS: Main: 194351104 22:37:31 INFO - PROCESS | 11306 | 22:37:34 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35144/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:37:34 INFO - PROCESS | 11306 | RSS: Main: 188231680 22:37:34 INFO - PROCESS | 11306 | 22:37:37 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35144/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:37:37 INFO - PROCESS | 11306 | RSS: Main: 193077248 22:37:37 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | Cycle 1(1): loaded http://localhost:35144/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35144/tests/kraken/driver.html?testName=ai-astar) 22:37:39 INFO - PROCESS | 11306 | RSS: Main: 244191232 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | __start_tp_report 22:37:39 INFO - PROCESS | 11306 | _x_x_mozilla_page_load 22:37:39 INFO - PROCESS | 11306 | _x_x_mozilla_page_load_details 22:37:39 INFO - PROCESS | 11306 | |i|pagename|runs| 22:37:39 INFO - PROCESS | 11306 | |0;ai-astar;102;111;110;113;112;111;111;111;110;111 22:37:39 INFO - PROCESS | 11306 | |1;audio-beat-detection;150;117;123;179;118;120;143;117;153;116 22:37:39 INFO - PROCESS | 11306 | |2;audio-dft;161;161;161;161;161;162;161;162;162;161 22:37:39 INFO - PROCESS | 11306 | |3;audio-fft;76;76;76;111;76;75;74;74;101;75 22:37:39 INFO - PROCESS | 11306 | |4;audio-oscillator;84;84;114;85;91;85;111;84;85;124 22:37:39 INFO - PROCESS | 11306 | |5;imaging-gaussian-blur;117;117;117;116;117;117;116;117;117;117 22:37:39 INFO - PROCESS | 11306 | |6;imaging-darkroom;146;146;145;146;146;145;146;146;146;146 22:37:39 INFO - PROCESS | 11306 | |7;imaging-desaturate;96;96;95;95;95;95;96;95;95;95 22:37:39 INFO - PROCESS | 11306 | |8;json-parse-financial;51;50;50;49;50;52;51;49;50;49 22:37:39 INFO - PROCESS | 11306 | |9;json-stringify-tinderbox;59;61;60;61;61;60;60;60;61;61 22:37:39 INFO - PROCESS | 11306 | |10;stanford-crypto-aes;84;78;75;77;85;80;76;74;75;80 22:37:39 INFO - PROCESS | 11306 | |11;stanford-crypto-ccm;125;122;122;137;119;120;129;126;162;136 22:37:39 INFO - PROCESS | 11306 | |12;stanford-crypto-pbkdf2;161;159;162;161;160;161;160;165;160;160 22:37:39 INFO - PROCESS | 11306 | |13;stanford-crypto-sha256-iterative;63;64;63;63;63;63;63;64;63;63 22:37:39 INFO - PROCESS | 11306 | __end_tp_report 22:37:39 INFO - PROCESS | 11306 | __start_cc_report 22:37:39 INFO - PROCESS | 11306 | _x_x_mozilla_cycle_collect,1689 22:37:39 INFO - PROCESS | 11306 | __end_cc_report 22:37:39 INFO - PROCESS | 11306 | __startTimestamp1470202659859__endTimestamp 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | ------- Summary: start ------- 22:37:39 INFO - PROCESS | 11306 | Number of tests: 14 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.00 stddev:3.01 (2.7%) stddev-sans-first:0.93 22:37:39 INFO - PROCESS | 11306 | Values: 102.0 111.0 110.0 113.0 112.0 111.0 111.0 111.0 110.0 111.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:133.00 stddev:21.59 (16.2%) stddev-sans-first:22.07 22:37:39 INFO - PROCESS | 11306 | Values: 150.0 117.0 123.0 179.0 118.0 120.0 143.0 117.0 153.0 116.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 22:37:39 INFO - PROCESS | 11306 | Values: 161.0 161.0 161.0 161.0 161.0 162.0 161.0 162.0 162.0 161.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.50 stddev:13.20 (17.5%) stddev-sans-first:13.86 22:37:39 INFO - PROCESS | 11306 | Values: 76.0 76.0 76.0 111.0 76.0 75.0 74.0 74.0 101.0 75.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:84.50 stddev:15.41 (18.2%) stddev-sans-first:15.85 22:37:39 INFO - PROCESS | 11306 | Values: 84.0 84.0 114.0 85.0 91.0 85.0 111.0 84.0 85.0 124.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:37:39 INFO - PROCESS | 11306 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 22:37:39 INFO - PROCESS | 11306 | Values: 146.0 146.0 145.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 22:37:39 INFO - PROCESS | 11306 | Values: 96.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.99 (2.0%) stddev-sans-first:1.00 22:37:39 INFO - PROCESS | 11306 | Values: 51.0 50.0 50.0 49.0 50.0 52.0 51.0 49.0 50.0 49.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:61.00 stddev:0.70 (1.1%) stddev-sans-first:0.53 22:37:39 INFO - PROCESS | 11306 | Values: 59.0 61.0 60.0 61.0 61.0 60.0 60.0 60.0 61.0 61.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:79.00 stddev:3.81 (4.8%) stddev-sans-first:3.46 22:37:39 INFO - PROCESS | 11306 | Values: 84.0 78.0 75.0 77.0 85.0 80.0 76.0 74.0 75.0 80.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#11] stanford-crypto-ccm Cycles:10 Average:129.80 Median:127.50 stddev:12.91 (10.1%) stddev-sans-first:13.57 22:37:39 INFO - PROCESS | 11306 | Values: 125.0 122.0 122.0 137.0 119.0 120.0 129.0 126.0 162.0 136.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:161.00 stddev:1.66 (1.0%) stddev-sans-first:1.76 22:37:39 INFO - PROCESS | 11306 | Values: 161.0 159.0 162.0 161.0 160.0 161.0 160.0 165.0 160.0 160.0 22:37:39 INFO - PROCESS | 11306 | 22:37:39 INFO - PROCESS | 11306 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 22:37:39 INFO - PROCESS | 11306 | Values: 63.0 64.0 63.0 63.0 63.0 63.0 63.0 64.0 63.0 63.0 22:37:39 INFO - PROCESS | 11306 | -------- Summary: end -------- 22:37:39 INFO - PROCESS | 11306 | 22:37:40 INFO - TEST-INFO | 11306: exit 0 22:37:40 INFO - TEST-OK | kraken | took 61590ms 22:37:40 INFO - SUITE-END | took 1177s 22:37:41 INFO - Completed test suite (00:19:37) 22:37:41 INFO - javascript benchmark 22:37:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3029.8485075746207, 3051.3439610483742, 3072.5698402223693, 3082.5222090575967, 3087.861046252918, 5238.8690282742955, 5282.709450980195, 5302.549283310923, 5362.410134885159, 5417.539509141718, 5286.074936886049, 5325.227841962923, 5341.225522299264, 5356.1697936819755, 5425.050616142169, 4738.147133515974, 4815.374001379816, 4828.082664293791, 4843.975780121112, 5028.974855125719, 4953.603711703073, 4960.925586116193, 4991.925121123186, 5001.624878134155, 5014.147594908874, 4789.2816077588295, 4801.855944321676, 4819.108464933998, 4865.635077369176, 4917.3607431033915, 4795.928061079087, 4809.446913604946, 4813.350197723297, 4876.146674331978, 4918.090153321611, 4876.049170411771, 4877.756112194412, 4883.02339532095, 4884.14527457697, 4923.113839508887, 5221.634485586011, 5269.314989051399, 5349.625526213166, 5365.06111430502, 5420.07858664028, 5802.88394232113, 5817.883642327172, 5820.097884827858, 5820.883582328372, 5829.213056237396, 5622.0, 5834.737436815353, 5841.0362290222065, 5843.328017278025, 5856.32652244993, 5284.550813180885, 5334.359876814807, 5342.545883599938, 5377.4084850666395, 5414.187871819258, 5883.0, 5947.197128387699, 5947.37552556984, 5948.0, 5948.078047902582, 5487.149491828773, 5684.090545512699, 5688.943110568925, 5689.203511508392, 5689.943100569024, 5646.435356464321, 5659.405762394889, 5700.572457065737, 5737.540996720252, 5786.797462088806, 5659.943400566024, 5696.287964004499, 5699.430056994267, 5702.116171993348, 5709.0865461525955, 5462.126059830488, 5721.084626459747, 5721.685307308138, 5722.799702010409, 5728.684922329228, 4303.655707543452, 4335.263005289045, 4357.346398040319, 4372.366006928977, 4415.712978656377, 3881.340172170738, 3892.824822882977, 3895.3183192941124, 3924.6271604197555, 3929.273084479323, 5345.438728933484, 5355.598330125256, 5357.249985002103, 5363.168708850135, 5409.459054094559, 5623.831285061455, 5677.886442271131, 5732.398098199629, 5742.3396309424115, 5799.3620701722775, 5338.599605029638, 5379.6234263601955, 5390.164524498709, 5400.945990540123, 5400.946815371043, 4904.558589726942, 4921.286413470027, 4928.162212423897, 4931.4821943696115, 4971.975140124277, 5748.626339287933, 5843.3864444233595, 5909.349971503131, 5916.0, 5997.190379298739, 5743.195952566728, 5743.282089738783, 5743.942560574341, 5745.511631511368, 5753.0, 5889.057750759858, 5936.376680448663, 5943.583949123693, 5948.167256584168, 5948.702564871825, 5535.418781027934, 5618.0, 5623.634463759842, 5673.205751194919, 5780.479756821738, 5768.451997060272, 5803.593748437569, 5806.593538452268, 5807.564432667651, 5811.331696854916, 5679.716014199356, 5692.174634678031, 5694.829155125187, 5697.003024470784, 5699.059655156852, 5457.099578569491, 5530.225768392348, 5588.832335029955, 5597.384287728428, 5682.374938756816, 5623.409541998195, 5627.971860140673, 5633.436656334334, 5634.267545219259, 5634.830955071355, 5675.148727690879, 5679.77280908759, 5718.685472299064, 5721.427857214246, 5737.340205876378, 5288.867778305573, 5382.811601594082, 5434.565234781129, 5459.208414779755, 5479.178123281539], "value": 5283.56536087621, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.1516216648026, 602.9336772954971, 604.5526310530206, 613.8987067133921, 636.8057742388571, 33919.83040084799, 34707.0, 36689.982050987186, 36852.26295474089, 44657.77671111648, 43558.0, 45785.31322030172, 46412.30381544279, 46479.30281045787, 47983.28025079626, 9080.455172689642, 9216.124468175512, 9224.907750922472, 9230.86153707695, 9235.861462078074, 8302.501849888995, 9058.682946096887, 9148.130927561871, 9149.634014639407, 10719.946400268027, 10794.568217271339, 11161.162912781534, 11224.326540407601, 11225.0, 11239.83140252897, 11137.220394572383, 11202.1598380121, 11207.103431725523, 11223.494942727597, 11235.21353505255, 10968.616098436516, 11000.834987475195, 11038.558457661684, 11039.116870650329, 11039.337639741561, 44568.0, 44915.32627010597, 44927.0, 44946.0, 44956.0, 3687.3362794696945, 3895.104126051024, 3994.121293315466, 4057.97971010146, 4079.714419990602, 33538.1615459613, 34057.148571285914, 34322.82838585791, 34463.31073378518, 34974.300513989576, 4324.372965919955, 4365.236083685342, 4378.912421751579, 4387.341898715186, 4519.615832654228, 376284.0, 376910.99999999726, 377806.0, 377821.0, 378393.0, 59065.40934590599, 59243.70378148081, 59449.811003780545, 59767.0, 59771.70114149401, 62720.0, 62853.0, 62901.37098628955, 63033.05450418247, 63091.36908630855, 15526.301316440562, 15912.76130858038, 15943.44197953066, 15950.680986380208, 15963.920180399024, 12219.877801222052, 12296.385180740926, 12328.19866708661, 12331.815022774666, 12339.93830030844, 12644.494220231089, 14231.50189743354, 16422.83577164237, 16429.91785041067, 16740.748888766677, 6166.691665416712, 6348.650824204621, 6413.422791948747, 6503.902441463382, 6597.274299827015, 995.4027583450016, 1108.3349990005931, 1114.5653195253858, 1132.2753437799815, 1144.5078616194949, 33275.83362083174, 33280.667193327754, 33552.328953420794, 33752.81865134709, 34108.82945585256, 1111.0278506306981, 1116.5980247111197, 1124.2748427264369, 1128.142611615162, 1166.416791604198, 17282.308707651555, 17471.82528174702, 17589.20848561818, 17623.823761762476, 17764.9111754443, 568780.0, 571747.0, 572227.0, 572551.0, 574151.0, 60914.0, 61119.0, 61162.694186529676, 61165.0, 61232.38767612267, 1261.8738126187309, 1345.710672205461, 1377.6969066805286, 1380.6686395265265, 1403.6701375176826, 33152.0, 34536.48195277023, 34735.0, 34885.476717848745, 34927.47608785921, 52207.477925220264, 52744.472555273955, 52760.0, 52801.47198528119, 52807.94384112373, 403499.0, 406744.0, 407140.0, 408524.0, 408686.0, 35713.821430892676, 40233.195336092525, 41821.0, 42083.7895810519, 42695.786521067195, 48656.0, 48867.75566122147, 49205.0, 49248.50751492583, 50516.49483505118, 41222.1755564893, 41962.580374195866, 42219.57780422157, 43435.56564434315, 43525.0, 41563.592196911006, 41667.37498937579, 42282.0, 42627.57372426236, 42820.57179428291, 41402.585974141075, 41525.169496610506, 41998.1600367997, 42315.3652695216, 42497.78751106225, 35864.0, 37830.62169378271, 38254.0, 38616.87495965626, 38791.03022424462, 28573.571396429485, 28592.857035715522, 28760.424791504473, 28778.42443151167, 29111.85444072766], "value": 20958.860474203448, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.885134459662, 384.6691845013288, 391.53016380343587, 393.9763614183149, 417.14485305124487, 14273.12775330397, 17743.55641108973, 18477.53806154845, 20285.39143825686, 20345.59308813823, 16398.26207820648, 17534.736978945326, 18375.35686250978, 18971.43085707431, 19176.0, 5772.855678608026, 5929.169916211734, 5940.61386009908, 5941.168236446901, 5963.701814909237, 5843.357230704619, 5878.941210587904, 5882.970585147068, 5885.058390657496, 5886.058230683092, 5783.6240644358195, 6169.383061693839, 6422.710978006001, 6452.257990331125, 6562.540622156451, 6567.310432404576, 6599.769008084718, 6727.899081513781, 6800.285969973131, 6845.863082738367, 5880.794172203952, 6069.757209711606, 6075.787347442818, 6078.240219972504, 6082.057281121433, 23366.182183623492, 23474.70889101099, 23490.412739681473, 24049.879750601136, 24186.63720044183, 2204.6472564389783, 2278.9886050569808, 2331.2773040357542, 2341.082428501329, 2838.801283910127, 10208.693739187762, 10265.794684106351, 10353.482325883751, 10434.843477347846, 10854.403007834566, 1965.5380985468403, 1994.1325523397225, 2022.5853699991615, 2028.4523178741656, 2067.65883629201, 84617.0, 86844.56577717197, 87592.56203719004, 88961.0, 92397.07602923951, 11219.214654974074, 11460.770784584261, 11463.254888432226, 11480.77038459226, 11745.823812642817, 16352.264148113363, 16358.018518888905, 16377.67244655124, 16409.261583228785, 16421.91789041071, 7753.922460775433, 7922.7227047053075, 8101.554414507141, 8268.09051004389, 8271.090180080186, 7499.587522686195, 7564.357029652541, 7628.237176282328, 7751.7674469766, 7797.883031754528, 4389.670774691911, 4710.434747830213, 4852.636052296021, 4873.122837889214, 5095.770690318945, 1869.4765465669634, 2001.599680063993, 2029.8287020173684, 2048.7336646235894, 2069.9793002069787, 885.6324625280565, 953.40780895904, 988.5551501824208, 1032.4528000159928, 1045.6340280901622, 19855.0, 20392.0, 20639.69040464394, 20669.379918602466, 20737.170513179604, 993.5677980078689, 995.8854731705802, 1015.421209910344, 1038.7818558102883, 1046.8691413573304, 10837.620683276047, 11427.200095993367, 11434.142439317084, 11505.769884602261, 11547.538098476134, 179364.0, 453641.0, 478176.0, 484153.0, 484231.0, 90962.54518727497, 91298.54350728204, 91775.5411222953, 92069.5396523013, 92476.0, 980.7302991677345, 996.3361186818031, 1026.5637104230673, 1034.1674951663833, 1037.2479952034714, 17086.829131708524, 17984.2806287747, 18221.0, 18335.0, 18623.81376186275, 40965.59034409618, 41155.7942210287, 41169.0, 41630.167396652505, 43114.353284701385, 53256.73371633117, 54502.72748636232, 54556.0, 56514.434855650914, 58698.119528207964, 8496.405251632326, 8878.378513503993, 9075.954620226856, 9152.0, 9160.679376222068, 10059.547320370748, 11998.58004969822, 13370.799438008244, 13473.797893031808, 14089.309282886119, 11657.708557285796, 11851.288922664668, 11859.40702964831, 12009.459574319002, 12196.085293602839, 11217.49521271561, 11713.062954963752, 11714.121440891797, 11731.88268117308, 11787.882121178678, 11283.774324513628, 12061.396930153633, 12086.153969222421, 12261.570845020382, 12303.614049599046, 8526.957365213135, 8587.699430519902, 8800.33997450181, 9109.908900910907, 9256.49089300095, 8563.486190828571, 10936.507857146607, 11209.0, 11644.475998579916, 11718.0], "value": 9355.26793570741, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.927293998831, 1464.363002094089, 1523.3297349166367, 1640.9261583228754, 1783.4025601423523, 8181.795455113618, 8500.787480312987, 8592.269657079149, 8818.559072046388, 8829.735107946772, 8638.784030399243, 8747.081556436562, 8758.605862736176, 8769.47383157011, 8776.517291548962, 2400.363903565552, 2445.021991203524, 2447.1679628926163, 2447.3759191406257, 2448.2655203438985, 2347.5891718949115, 2383.6067048936993, 2395.317334559642, 2397.0891061396733, 2398.3164798032562, 2380.833341666084, 2390.605550084234, 2396.880155992202, 2422.2127808462233, 2423.3578101803037, 2336.960052776515, 2340.9648855267183, 2342.906283748648, 2346.272655476799, 2386.3198988288254, 2301.5396920615776, 2322.210448447519, 2322.8606283623035, 2324.3491822289784, 2327.8486898351553, 8665.0, 8718.564071796385, 8827.77930551735, 8829.911700882973, 8852.8672069919, 5186.377634683824, 5414.7834086636485, 5423.023855705971, 5427.131658934591, 5427.375851777057, 3429.639887811769, 3463.8787642432644, 3508.7894726316504, 3522.84147213376, 3530.064532898784, 1144.4621028116771, 1148.4200478758173, 1155.5493357590547, 1160.1414952934851, 1183.9171258011943, 12106.697332566668, 12126.393680316038, 12171.208871423241, 12216.694582635417, 12216.877831221664, 4464.419625448671, 4575.565321294472, 4743.762811859393, 4747.501512341223, 4771.928421073687, 9769.706908792748, 9771.31600787938, 9786.0, 9786.314957952874, 9797.0, 5370.3287089113865, 5373.247745315659, 5384.0847056000575, 5390.19147127934, 5390.353157621033, 4326.783660816946, 4708.293755936568, 4860.246661767361, 4899.363082799213, 5013.147764879979, 3855.51806024247, 3857.1128640412303, 3964.108075683006, 3990.7206495545033, 4029.979850100731, 1179.5281887245171, 1183.2900259844196, 1186.4127257007835, 1196.342011893455, 1208.1965492947268, 938.8169306985209, 944.6693657220055, 950.12588418655, 955.8805149356331, 986.9654562090292, 5569.554435645138, 5670.206171136044, 5687.829365119053, 5750.654960702288, 5839.795607153814, 454.4069988664758, 458.0998338265258, 462.50973967594456, 466.91128685549637, 468.86402943146106, 4115.9176816464105, 4179.477565304337, 4198.6221240088535, 4287.849925252601, 4290.935635965463, 10762.515686794113, 11102.500387482422, 11149.944250278697, 11161.386123763272, 11168.944155279172, 10901.83647245292, 10947.890521094847, 11018.898110188917, 11039.889601104047, 11085.778284434266, 7830.530168189927, 8215.219554142346, 8267.958660206781, 8303.543305118277, 8335.87465692136, 5995.0, 6003.339632640494, 6128.142060111499, 6161.476274516716, 6199.225096862892, 9664.420134791935, 9891.851622225528, 9930.404175749478, 10172.033656802592, 10174.236932229964, 10073.0, 10312.896871031193, 10318.845217321896, 10336.844947325946, 10464.00591943764, 7071.36357727807, 7618.123915749759, 7806.531608103532, 7852.646630901738, 7860.0567931848545, 8643.697470588499, 8739.257163141205, 8747.475151490931, 8759.649614015369, 8767.386282960386, 7723.7682869514, 7725.690972361042, 7863.646135923783, 7943.642536086006, 8019.318357939641, 7445.0, 7554.508956917783, 7569.47013709035, 7575.924240757522, 7586.772396828104, 7109.857802844018, 7216.675249613675, 7218.314260145278, 7230.6384680766805, 7230.783076507713, 4872.780724867319, 4989.476105008922, 5039.26930595069, 5040.420351659606, 5166.509181627739, 5099.796008159632, 5253.632245742761, 5304.283921670525, 5345.54562862161, 5352.892942141213], "value": 4568.2969456689725, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.13896770268724, 318.10136364769517, 325.95762550868375, 328.7238719475643, 330.38548300161693, 5207.880305734267, 5221.425643179256, 5227.372715274168, 5241.187615919539, 5255.343082114736, 5192.636515443921, 5201.895962080756, 5202.609804264685, 5216.113260745673, 5233.21501774733, 3898.356771132767, 3948.756141815319, 3994.9800250998705, 4018.1361007383407, 4024.859129930453, 4018.4575082363945, 4091.018155642654, 4091.079507110906, 4099.364598487239, 4116.320807066831, 4059.289624315748, 4059.411385349137, 4068.5524592294823, 4072.6538244249273, 4075.694322925792, 4063.5618628822617, 4070.8778736637946, 4079.4900637420324, 4079.9796001019804, 4080.9387859182134, 3392.4402473591826, 3456.343294773985, 3543.291341731664, 3603.855845766166, 3626.691731202851, 5011.148104822152, 5011.774470148852, 5012.398512178525, 5020.874478138076, 5035.84892453227, 4703.2004559225015, 4703.6829687687505, 4705.788239529195, 4705.788239529229, 4714.363560919301, 5299.973500132513, 5389.0, 5409.648372855751, 5416.485433883814, 5430.511253987121, 3981.3430783920617, 3984.7609143451195, 3985.329829014567, 3988.7407318524492, 3993.520777506689, 5373.6775793452125, 5411.645934018675, 5630.0, 5631.0, 5635.154726790973, 4011.1777085697363, 4053.574374690615, 4060.675145988314, 4109.650679692199, 4110.753354798721, 4146.91706165875, 4152.736758466643, 4161.97919010403, 4167.416561681367, 4169.332906734969, 4527.275635898306, 4688.460827004938, 4696.618434726991, 4708.8116475341285, 4711.528847115261, 5421.728913554307, 5457.61796674229, 5462.617616766788, 5463.453654634584, 5470.589705772083, 1417.844037155912, 1432.6776475292975, 1434.956951291463, 1436.5977526292654, 1439.5465428390023, 858.8539948208819, 915.8689019061454, 924.4222361024359, 930.9813803724023, 934.8223837470858, 4660.277656963176, 4675.602573781199, 4683.6721429500285, 4692.976535117303, 4724.771559394582, 4950.0, 4961.925571116436, 4967.105920934194, 4968.22992436173, 4992.553648712105, 4680.321353403738, 4696.718196908197, 4697.553732395417, 4703.0, 4940.7035577865445, 2948.395578906319, 2969.6436427628823, 2976.7320941115404, 2996.0, 3019.637643482796, 5797.130430435468, 5812.912806307909, 5820.592558520862, 5858.121281807677, 5877.147813567094, 5508.338999320107, 5717.399673034354, 5737.254156959652, 5737.3688894221605, 5751.683657398883, 5755.942440575541, 5794.536437085023, 5812.622179558315, 5829.970850145807, 5837.182794408701, 5414.431484694051, 5633.549316054624, 5638.774449021993, 5643.689597072201, 5655.066913959151, 5549.472800083986, 5553.861153471195, 5555.666660000413, 5563.443655634404, 5581.5813813963305, 5546.944530554642, 5670.858228544154, 5679.716014199191, 5704.1443783432815, 5814.970925145347, 3875.3411919974237, 3899.824507897095, 3989.2221016902586, 4009.4787677601726, 4014.6186112319283, 5605.467480589337, 5609.49514543693, 5612.887742245214, 5618.185363122406, 5618.763871948165, 5281.867953301198, 5487.996881138435, 5533.833984980457, 5536.584756143225, 5537.612367134262, 5465.6447330923365, 5470.370907345706, 5499.047838017446, 5499.285092937893, 5503.477169668875, 5327.573794096541, 5400.729963501731, 5477.452254774491, 5507.9449205507435, 5514.696691681996, 5561.5272701820795, 5566.721663916869, 5576.302962129734, 5578.665280083045, 5585.385607583244, 5418.810341638024, 5424.457554244702, 5437.211604317431, 5439.960014994377, 5439.972800136133], "value": 4141.02389065641, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.7978965889549, 747.4618274842112, 774.4733581164808, 788.8777239527875, 818.2799136759658, 14155.15069095855, 14972.475963341285, 15004.699906001873, 15014.774778378305, 15076.020058696191, 15332.15673137977, 15486.69026619467, 15489.535313940572, 15492.070475771461, 15493.84506154938, 643.4562794438692, 770.1566784371105, 775.8603451378749, 787.358302983067, 803.6544285957021, 808.9110197878229, 815.1074573342211, 815.3680897304577, 815.4617952151581, 815.7878951472601, 857.2584714222215, 862.374778285741, 866.5320726807516, 870.0603348383762, 870.1255238485313, 674.5177198303188, 679.2663922963212, 681.4752640466862, 820.4871955028108, 857.1728282207669, 610.6763415389851, 622.2968046107915, 622.4366947912147, 622.498888394842, 623.7754408412968, 14874.553763387008, 14881.255937203205, 14887.404503819835, 14895.0, 14901.0, 13637.113587616872, 13696.93151534246, 14084.859151408456, 14146.71706565863, 14221.21783301918, 12341.382930853422, 12559.623211303675, 12655.620331390073, 12794.232346059172, 12812.679683008, 4287.8070486828165, 4349.456317960255, 4358.8910277243, 4421.646268298528, 4588.105319462706, 83645.16354836496, 84074.15925840786, 84109.57945210235, 84577.57711211467, 87699.0, 11064.907609617003, 11121.888781112248, 11135.33188008722, 11141.888581114246, 11311.038561722182, 21424.892875535836, 21454.892725536272, 21459.0, 21459.8927005364, 21468.141274349164, 7775.844483110306, 7914.129445761077, 7984.20157984197, 8046.195380462024, 8241.670333186725, 4423.159599676052, 4574.245249533857, 4601.493835678073, 4654.743989080565, 4705.741184234831, 3316.8673253069605, 3408.4716868884866, 3442.397580423416, 3445.672661097192, 3800.1449673823167, 639.5043841023152, 667.549404152205, 667.9131712877295, 669.3808227389645, 684.3191024930227, 593.4569868570296, 599.4814485470037, 695.6139342664869, 736.9042024536776, 745.6532712288812, 9603.137177036971, 9781.217502599917, 9965.651202207888, 10074.798504029879, 10264.486775661187, 638.9265234497999, 668.6556423441931, 708.5005071424633, 741.8405042915691, 747.4937999930145, 4552.6130278926, 4909.563285867269, 4932.7533623319405, 4952.62855285855, 4969.0807200668, 107762.46118769357, 116361.41819291019, 119698.0, 122433.0, 122467.3876630629, 84641.0, 89198.0, 90916.54541727249, 111978.44010779895, 112267.43866280779, 77326.61336693393, 87146.0, 87967.0, 89562.55218723735, 90841.09158908458, 9756.0, 9818.405663280986, 9865.506724663595, 10060.69817905464, 10204.285700000928, 49063.50936490589, 50820.23769643384, 50992.49007509877, 52522.73738631282, 53428.0, 73278.63360683163, 74735.0, 76925.0, 77143.55403125074, 78566.6071669638, 11732.354720490455, 12116.75766484683, 13083.476660933457, 13209.471621135048, 13534.729305414034, 14449.422023118957, 15395.769063463833, 15510.767338490157, 15736.606584835014, 15767.921160394124, 14548.636284092983, 14846.480373186887, 15280.159591222146, 15325.0, 15709.371625134867, 13953.720925581634, 14141.151530908179, 14335.441779051067, 14551.0, 14600.926995364955, 13957.790633140714, 14075.0, 14094.436222550983, 14390.640233994234, 14556.199409032604, 11729.824052639047, 11756.118291128028, 12059.550382652837, 12417.19288246261, 12492.31292278897, 10480.790384192122, 10611.363318200933, 11933.164678472423, 12009.579664712042, 12112.878871211527], "value": 7648.92854105391, "unit": "score"}], "name": "dromaeo_css", "value": 7288.59295961342, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 113.0, 112.0, 111.0, 111.0, 111.0, 110.0, 111.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 117.0, 123.0, 179.0, 118.0, 120.0, 143.0, 117.0, 153.0, 116.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 161.0, 162.0, 162.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 111.0, 76.0, 75.0, 74.0, 74.0, 101.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 114.0, 85.0, 91.0, 85.0, 111.0, 84.0, 85.0, 124.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 49.0, 50.0, 52.0, 51.0, 49.0, 50.0, 49.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 61.0, 61.0, 60.0, 60.0, 60.0, 61.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 78.0, 75.0, 77.0, 85.0, 80.0, 76.0, 74.0, 75.0, 80.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 122.0, 122.0, 137.0, 119.0, 120.0, 129.0, 126.0, 162.0, 136.0], "value": 129.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 162.0, 161.0, 160.0, 161.0, 160.0, 165.0, 160.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 63.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1481.9, "alertThreshold": 2.0}]} 22:37:41 INFO - Return code: 0 22:37:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:37:41 INFO - # TBPL SUCCESS # 22:37:41 INFO - Running post-action listener: _resource_record_post_action 22:37:41 INFO - [mozharness: 2016-08-03 05:37:41.176546Z] Finished run-tests step (success) 22:37:41 INFO - Running post-run listener: _resource_record_post_run 22:37:41 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 0; Write bytes: 538750976; Read time: 0; Write time: 419712 22:37:41 INFO - TinderboxPrint: CPU usage
13.0% 22:37:41 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 22:37:41 INFO - TinderboxPrint: I/O write bytes / time
538,750,976 / 419,712 22:37:41 INFO - TinderboxPrint: CPU idle
8,293.1 (86.9%) 22:37:41 INFO - TinderboxPrint: CPU user
1,196.1 (12.5%) 22:37:41 INFO - TinderboxPrint: Swap in / out
0 / 0 22:37:41 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247832576; Read time: 0; Write time: 260712 22:37:41 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 290582528; Read time: 0; Write time: 159000 22:37:41 INFO - Running post-run listener: _upload_blobber_files 22:37:41 INFO - Blob upload gear active. 22:37:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:37:41 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 22:37:41 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:37:41 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:37:42 INFO - (blobuploader) - INFO - Open directory for files ... 22:37:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:37:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:37:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:43 INFO - (blobuploader) - INFO - Done attempting. 22:37:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:37:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:37:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:46 INFO - (blobuploader) - INFO - Done attempting. 22:37:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:37:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:37:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:47 INFO - (blobuploader) - INFO - Done attempting. 22:37:47 INFO - (blobuploader) - INFO - Iteration through files over. 22:37:47 INFO - Return code: 0 22:37:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:37:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:37:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/17f4979efe2651e8d369fdd2b2242f9829c76eb674edf85cd35edd5a037744cbd0a1a2351c710404aae5fb2722b48c9116223324197a85a1f2b2c1e3eb847ec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5c02a5d82ead44e8da22ef5f27734e6a0b25154513e3938fe4e23e2c4b72efc6a02a77e9c18ffdabc5d60ed5d79a57fdc611851f6e8b6b08d85b8247dbd614c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/36c4ff5b0b44a7bd50530b2f26d7d30f235adca8602f463e9d369a609e8a3f2de571255e5cc4ac0ddf15be61841f32d250c077c803d2327c60092196182a82c8"} 22:37:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:37:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:37:47 INFO - Contents: 22:37:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/17f4979efe2651e8d369fdd2b2242f9829c76eb674edf85cd35edd5a037744cbd0a1a2351c710404aae5fb2722b48c9116223324197a85a1f2b2c1e3eb847ec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5c02a5d82ead44e8da22ef5f27734e6a0b25154513e3938fe4e23e2c4b72efc6a02a77e9c18ffdabc5d60ed5d79a57fdc611851f6e8b6b08d85b8247dbd614c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/36c4ff5b0b44a7bd50530b2f26d7d30f235adca8602f463e9d369a609e8a3f2de571255e5cc4ac0ddf15be61841f32d250c077c803d2327c60092196182a82c8"} 22:37:47 INFO - Running post-run listener: copy_logs_to_upload_dir 22:37:47 INFO - Copying logs to upload dir... 22:37:47 INFO - mkdir: /builds/slave/test/build/upload/logs 22:37:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.213003 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-02 22:37:45.600761) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 22:37:45.604863) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/17f4979efe2651e8d369fdd2b2242f9829c76eb674edf85cd35edd5a037744cbd0a1a2351c710404aae5fb2722b48c9116223324197a85a1f2b2c1e3eb847ec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5c02a5d82ead44e8da22ef5f27734e6a0b25154513e3938fe4e23e2c4b72efc6a02a77e9c18ffdabc5d60ed5d79a57fdc611851f6e8b6b08d85b8247dbd614c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/36c4ff5b0b44a7bd50530b2f26d7d30f235adca8602f463e9d369a609e8a3f2de571255e5cc4ac0ddf15be61841f32d250c077c803d2327c60092196182a82c8"} build_url:https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011272 build_url: 'https://queue.taskcluster.net/v1/task/BjwhUd7fRu6EIy7zLg4VYA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/17f4979efe2651e8d369fdd2b2242f9829c76eb674edf85cd35edd5a037744cbd0a1a2351c710404aae5fb2722b48c9116223324197a85a1f2b2c1e3eb847ec8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5c02a5d82ead44e8da22ef5f27734e6a0b25154513e3938fe4e23e2c4b72efc6a02a77e9c18ffdabc5d60ed5d79a57fdc611851f6e8b6b08d85b8247dbd614c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/36c4ff5b0b44a7bd50530b2f26d7d30f235adca8602f463e9d369a609e8a3f2de571255e5cc4ac0ddf15be61841f32d250c077c803d2327c60092196182a82c8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 22:37:45.633575) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 22:37:45.634299) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1470200447.274459-971803733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 22:37:45.720877) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 22:37:45.721713) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 22:37:45.722242) ========= ========= Total master_lag: 0.16 =========