builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1464563057.83 results: success (0) buildid: 20160529133059 builduid: 61a795ac97de4377ba3ec35befcd1fb4 revision: 0c174794580dcc4d40eace91fed1bdbd87491b2e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:17.833762) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:17.834563) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:17.834901) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:17.875087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:17.875382) ========= 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=8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-29 16:04:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-05-29 16:04:18 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114586 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:18.020656) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:18.021029) ========= 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=8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021129 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:18.090984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:18.091358) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 0c174794580dcc4d40eace91fed1bdbd87491b2e --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 0c174794580dcc4d40eace91fed1bdbd87491b2e --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=8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-29 16:04:18,286 truncating revision to first 12 chars 2016-05-29 16:04:18,286 Setting DEBUG logging. 2016-05-29 16:04:18,286 attempt 1/10 2016-05-29 16:04:18,286 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/0c174794580d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-29 16:04:18,768 unpacking tar archive at: mozilla-inbound-0c174794580d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.891426 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:19.001204) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:19.001725) ========= script_repo_revision: 0c174794580dcc4d40eace91fed1bdbd87491b2e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:19.002391) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:19.002897) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-29 16:04:19.024441) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-05-29 16:04:19.024783) ========= /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=8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:04:19 INFO - MultiFileLogger online at 20160529 16:04:19 in /builds/slave/test 16:04:19 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 16:04:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:04:19 INFO - {'append_to_log': False, 16:04:19 INFO - 'base_work_dir': '/builds/slave/test', 16:04:19 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:04:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:04:19 INFO - 'branch': 'Mozilla-Inbound', 16:04:19 INFO - 'buildbot_json_path': 'buildprops.json', 16:04:19 INFO - 'config_files': ('talos/linux_config.py',), 16:04:19 INFO - 'default_actions': ('clobber', 16:04:19 INFO - 'read-buildbot-config', 16:04:19 INFO - 'download-and-extract', 16:04:19 INFO - 'populate-webroot', 16:04:19 INFO - 'create-virtualenv', 16:04:19 INFO - 'install', 16:04:19 INFO - 'run-tests'), 16:04:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:04:19 INFO - 'download_minidump_stackwalk': True, 16:04:19 INFO - 'download_symbols': 'ondemand', 16:04:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:04:19 INFO - 'tooltool.py': '/tools/tooltool.py', 16:04:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:04:19 INFO - '/tools/misc-python/virtualenv.py')}, 16:04:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:04:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:04:19 INFO - 'installer_path': 'installer.exe', 16:04:19 INFO - 'log_level': 'info', 16:04:19 INFO - 'log_name': 'talos', 16:04:19 INFO - 'log_to_console': True, 16:04:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:04:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:04:19 INFO - 'opt_config_files': (), 16:04:19 INFO - 'pip_index': False, 16:04:19 INFO - 'sps_profile': False, 16:04:19 INFO - 'sps_profile_interval': 0, 16:04:19 INFO - 'suite': 'dromaeojs', 16:04:19 INFO - 'system_bits': '32', 16:04:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:04:19 INFO - 'title': 'talos-linux64-ix-038', 16:04:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:04:19 INFO - 'use_talos_json': True, 16:04:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:04:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:04:19 INFO - 'work_dir': 'build'} 16:04:19 INFO - ##### 16:04:19 INFO - ##### Running clobber step. 16:04:19 INFO - ##### 16:04:19 INFO - Running pre-action listener: _resource_record_pre_action 16:04:19 INFO - Running main action method: clobber 16:04:19 INFO - rmtree: /builds/slave/test/build 16:04:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:04:19 INFO - Running post-action listener: _resource_record_post_action 16:04:19 INFO - ##### 16:04:19 INFO - ##### Finished clobber step (success) 16:04:19 INFO - ##### 16:04:19 INFO - ##### 16:04:19 INFO - ##### Running read-buildbot-config step. 16:04:19 INFO - ##### 16:04:19 INFO - Running pre-action listener: _resource_record_pre_action 16:04:19 INFO - Running main action method: read_buildbot_config 16:04:19 INFO - Using buildbot properties: 16:04:19 INFO - { 16:04:19 INFO - "project": "", 16:04:19 INFO - "product": "firefox", 16:04:19 INFO - "script_repo_revision": "production", 16:04:19 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:04:19 INFO - "repository": "", 16:04:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:04:19 INFO - "buildid": "20160529133059", 16:04:19 INFO - "pgo_build": "True", 16:04:19 INFO - "basedir": "/builds/slave/test", 16:04:19 INFO - "buildnumber": 1006, 16:04:19 INFO - "slavename": "talos-linux64-ix-038", 16:04:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:04:19 INFO - "platform": "ubuntu64_hw", 16:04:19 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:04:19 INFO - "branch": "mozilla-inbound", 16:04:19 INFO - "repo_path": "integration/mozilla-inbound", 16:04:19 INFO - "revision": "0c174794580dcc4d40eace91fed1bdbd87491b2e", 16:04:19 INFO - "stage_platform": "linux64-pgo", 16:04:19 INFO - "builduid": "61a795ac97de4377ba3ec35befcd1fb4", 16:04:19 INFO - "slavebuilddir": "test" 16:04:19 INFO - } 16:04:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 16:04:19 INFO - Running post-action listener: _resource_record_post_action 16:04:19 INFO - ##### 16:04:19 INFO - ##### Finished read-buildbot-config step (success) 16:04:19 INFO - ##### 16:04:19 INFO - ##### 16:04:19 INFO - ##### Running download-and-extract step. 16:04:19 INFO - ##### 16:04:19 INFO - Running pre-action listener: _resource_record_pre_action 16:04:19 INFO - Running main action method: download_and_extract 16:04:19 INFO - mkdir: /builds/slave/test/build/tests 16:04:19 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')]} 16:04:19 INFO - https://queue.taskcluster.net/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:04:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:04:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:04:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:04:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:04:24 INFO - Downloaded 1361 bytes. 16:04:24 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:04:24 INFO - Using the following test package requirements: 16:04:24 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:04:24 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:04:24 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:04:24 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:24 INFO - u'jsshell-linux-x86_64.zip'], 16:04:24 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:04:24 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:04:24 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:04:24 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:04:24 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:04:24 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:04:24 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:04:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:04:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:24 INFO - https://queue.taskcluster.net/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:04:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:04:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:04:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:04:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:04:28 INFO - Downloaded 24409834 bytes. 16:04:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:04:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:04:29 INFO - Return code: 0 16:04:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:29 INFO - https://queue.taskcluster.net/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:04:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:04:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:04:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:04:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:04:31 INFO - Downloaded 24409834 bytes. 16:04:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:04:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:04:32 INFO - Return code: 0 16:04: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')]} 16:04:32 INFO - https://queue.taskcluster.net/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:04:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:04:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:04:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:04:33 INFO - Downloaded 10834055 bytes. 16:04:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:04:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:04:34 INFO - Return code: 0 16:04: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')]} 16:04:34 INFO - https://queue.taskcluster.net/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:04:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:04:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:04:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:04:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:04:35 INFO - Downloaded 58871433 bytes. 16:04:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:04:35 INFO - mkdir: /builds/slave/test/properties 16:04:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:04:35 INFO - Writing to file /builds/slave/test/properties/build_url 16:04:35 INFO - Contents: 16:04:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:04:36 INFO - Running post-action listener: _resource_record_post_action 16:04:36 INFO - Running post-action listener: set_extra_try_arguments 16:04:36 INFO - ##### 16:04:36 INFO - ##### Finished download-and-extract step (success) 16:04:36 INFO - ##### 16:04:36 INFO - ##### 16:04:36 INFO - ##### Running populate-webroot step. 16:04:36 INFO - ##### 16:04:36 INFO - Running pre-action listener: _resource_record_pre_action 16:04:36 INFO - Running main action method: populate_webroot 16:04:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:04:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:04:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:04:36 INFO - '--tppagecycles', 16:04:36 INFO - '7'], 16:04:36 INFO - 'tests': ['tsvgm']}}, 16:04:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:04:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:04:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:04:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:04:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:04:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:04:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['damp', 'tps']}, 16:04:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'tests': ['damp', 'tps']}, 16:04:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['dromaeo_dom']}, 16:04:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:04:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'other-e10s': {'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['tsvgx', 16:04:36 INFO - 'tsvgr_opacity', 16:04:36 INFO - 'tart', 16:04:36 INFO - 'tscrollx', 16:04:36 INFO - 'cart']}, 16:04:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:04:36 INFO - 'tsvgr_opacity', 16:04:36 INFO - 'tart', 16:04:36 INFO - 'tscrollx', 16:04:36 INFO - 'cart']}, 16:04:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['tp5o']}, 16:04:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'tests': ['tp5o']}, 16:04:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'talos_options': ['--disable-e10s', 16:04:36 INFO - '--xperf_path', 16:04:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:04:36 INFO - 'tests': ['tp5n']}, 16:04:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'talos_options': ['--xperf_path', 16:04:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:04:36 INFO - 'tests': ['tp5n']}}, 16:04:36 INFO - 'talos.zip': {'path': '', 16:04:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:04:36 INFO - Running post-action listener: _resource_record_post_action 16:04:36 INFO - ##### 16:04:36 INFO - ##### Finished populate-webroot step (success) 16:04:36 INFO - ##### 16:04:36 INFO - ##### 16:04:36 INFO - ##### Running create-virtualenv step. 16:04:36 INFO - ##### 16:04:36 INFO - Running pre-action listener: _resource_record_pre_action 16:04:36 INFO - Running main action method: create_virtualenv 16:04:36 INFO - Creating virtualenv /builds/slave/test/build/venv 16:04:36 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 16:04:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:04:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:04:36 INFO - Using real prefix '/usr' 16:04:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:04:37 INFO - Installing distribute.............................................................................................................................................................................................done. 16:04:39 INFO - Installing pip.................done. 16:04:39 INFO - Return code: 0 16:04:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:04:39 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')]} 16:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:39 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')]} 16:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:39 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 0x7fbe309dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d419c0>, '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 0x2d428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d42d50>, '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': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', '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 16:04:39 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 16:04:39 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 16:04:39 INFO - Using env: {'DISPLAY': ':0', 16:04:39 INFO - 'HOME': '/home/cltbld', 16:04:39 INFO - 'LANG': 'en_US.UTF-8', 16:04:39 INFO - 'LANGUAGE': 'en_US:en', 16:04:39 INFO - 'LOGNAME': 'cltbld', 16:04:39 INFO - 'MAIL': '/var/mail/cltbld', 16:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:39 INFO - 'MOZ_NO_REMOTE': '1', 16:04:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:39 INFO - 'NO_EM_RESTART': '1', 16:04:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:39 INFO - 'PWD': '/builds/slave/test', 16:04:39 INFO - 'SHELL': '/bin/bash', 16:04:39 INFO - 'SHLVL': '1', 16:04:39 INFO - 'TERM': 'linux', 16:04:39 INFO - 'TMOUT': '86400', 16:04:39 INFO - 'USER': 'cltbld', 16:04:39 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', 16:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:39 INFO - '_': '/tools/buildbot/bin/python'} 16:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:39 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 16:04:39 INFO - Cleaning up... 16:04:39 INFO - Return code: 0 16:04:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:04:39 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')]} 16:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:39 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')]} 16:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:39 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 0x7fbe309dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d419c0>, '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 0x2d428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d42d50>, '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': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', '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 16:04:39 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 16:04:39 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 16:04:39 INFO - Using env: {'DISPLAY': ':0', 16:04:39 INFO - 'HOME': '/home/cltbld', 16:04:39 INFO - 'LANG': 'en_US.UTF-8', 16:04:39 INFO - 'LANGUAGE': 'en_US:en', 16:04:39 INFO - 'LOGNAME': 'cltbld', 16:04:39 INFO - 'MAIL': '/var/mail/cltbld', 16:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:39 INFO - 'MOZ_NO_REMOTE': '1', 16:04:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:39 INFO - 'NO_EM_RESTART': '1', 16:04:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:39 INFO - 'PWD': '/builds/slave/test', 16:04:39 INFO - 'SHELL': '/bin/bash', 16:04:39 INFO - 'SHLVL': '1', 16:04:39 INFO - 'TERM': 'linux', 16:04:39 INFO - 'TMOUT': '86400', 16:04:39 INFO - 'USER': 'cltbld', 16:04:39 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', 16:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:39 INFO - '_': '/tools/buildbot/bin/python'} 16:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:39 INFO - Downloading/unpacking psutil>=3.1.1 16:04:39 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 16:04:39 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 16:04:39 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 16:04:39 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 16:04:39 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 16:04:39 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 16:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:04:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:04:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:04:41 INFO - Installing collected packages: psutil 16:04:41 INFO - Running setup.py install for psutil 16:04:41 INFO - building 'psutil._psutil_linux' extension 16:04:41 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 16:04:41 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 16:04:41 INFO - building 'psutil._psutil_posix' extension 16:04:41 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 16:04:42 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 16:04:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:04:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:04:42 INFO - Successfully installed psutil 16:04:42 INFO - Cleaning up... 16:04:42 INFO - Return code: 0 16:04:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe309dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d419c0>, '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 0x2d428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d42d50>, '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': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', '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 16:04:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:04:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:04:42 INFO - Using env: {'DISPLAY': ':0', 16:04:42 INFO - 'HOME': '/home/cltbld', 16:04:42 INFO - 'LANG': 'en_US.UTF-8', 16:04:42 INFO - 'LANGUAGE': 'en_US:en', 16:04:42 INFO - 'LOGNAME': 'cltbld', 16:04:42 INFO - 'MAIL': '/var/mail/cltbld', 16:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:42 INFO - 'MOZ_NO_REMOTE': '1', 16:04:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:42 INFO - 'NO_EM_RESTART': '1', 16:04:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:42 INFO - 'PWD': '/builds/slave/test', 16:04:42 INFO - 'SHELL': '/bin/bash', 16:04:42 INFO - 'SHLVL': '1', 16:04:42 INFO - 'TERM': 'linux', 16:04:42 INFO - 'TMOUT': '86400', 16:04:42 INFO - 'USER': 'cltbld', 16:04:42 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', 16:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:42 INFO - '_': '/tools/buildbot/bin/python'} 16:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:04:44 INFO - Installing collected packages: mozsystemmonitor 16:04:44 INFO - Running setup.py install for mozsystemmonitor 16:04:44 INFO - Successfully installed mozsystemmonitor 16:04:44 INFO - Cleaning up... 16:04:44 INFO - Return code: 0 16:04:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:04:44 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')]} 16:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:44 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')]} 16:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:44 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 0x7fbe309dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d419c0>, '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 0x2d428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d42d50>, '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': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', '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 16:04:44 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 16:04:44 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 16:04:44 INFO - Using env: {'DISPLAY': ':0', 16:04:44 INFO - 'HOME': '/home/cltbld', 16:04:44 INFO - 'LANG': 'en_US.UTF-8', 16:04:44 INFO - 'LANGUAGE': 'en_US:en', 16:04:44 INFO - 'LOGNAME': 'cltbld', 16:04:44 INFO - 'MAIL': '/var/mail/cltbld', 16:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:44 INFO - 'MOZ_NO_REMOTE': '1', 16:04:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:44 INFO - 'NO_EM_RESTART': '1', 16:04:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:44 INFO - 'PWD': '/builds/slave/test', 16:04:44 INFO - 'SHELL': '/bin/bash', 16:04:44 INFO - 'SHLVL': '1', 16:04:44 INFO - 'TERM': 'linux', 16:04:44 INFO - 'TMOUT': '86400', 16:04:44 INFO - 'USER': 'cltbld', 16:04:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', 16:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:44 INFO - '_': '/tools/buildbot/bin/python'} 16:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:46 INFO - Downloading blobuploader-1.2.4.tar.gz 16:04:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:04:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:04:46 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 16:04:46 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 16:04:46 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 16:04:46 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 16:04:46 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 16:04:46 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 16:04:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:04:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:47 INFO - Downloading docopt-0.6.1.tar.gz 16:04:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:04:47 INFO - Installing collected packages: blobuploader, requests, docopt 16:04:47 INFO - Running setup.py install for blobuploader 16:04:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:04:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:04:47 INFO - Running setup.py install for requests 16:04:47 INFO - Running setup.py install for docopt 16:04:47 INFO - Successfully installed blobuploader requests docopt 16:04:47 INFO - Cleaning up... 16:04:47 INFO - Return code: 0 16:04:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04:47 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')]} 16:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:47 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')]} 16:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:47 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 0x7fbe309dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d419c0>, '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 0x2d428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d42d50>, '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': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', '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 16:04:47 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 16:04:47 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 16:04:47 INFO - Using env: {'DISPLAY': ':0', 16:04:47 INFO - 'HOME': '/home/cltbld', 16:04:47 INFO - 'LANG': 'en_US.UTF-8', 16:04:47 INFO - 'LANGUAGE': 'en_US:en', 16:04:47 INFO - 'LOGNAME': 'cltbld', 16:04:47 INFO - 'MAIL': '/var/mail/cltbld', 16:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:47 INFO - 'MOZ_NO_REMOTE': '1', 16:04:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:47 INFO - 'NO_EM_RESTART': '1', 16:04:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:47 INFO - 'PWD': '/builds/slave/test', 16:04:47 INFO - 'SHELL': '/bin/bash', 16:04:47 INFO - 'SHLVL': '1', 16:04:47 INFO - 'TERM': 'linux', 16:04:47 INFO - 'TMOUT': '86400', 16:04:47 INFO - 'USER': 'cltbld', 16:04:47 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', 16:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:47 INFO - '_': '/tools/buildbot/bin/python'} 16:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:04:48 INFO - Running setup.py (path:/tmp/pip-biuB3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:04:48 INFO - Running setup.py (path:/tmp/pip-DCnsRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:04:48 INFO - Running setup.py (path:/tmp/pip-mpswJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:04:48 INFO - Running setup.py (path:/tmp/pip-aUarzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:04:48 INFO - Running setup.py (path:/tmp/pip-D6B4Vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:04:48 INFO - Running setup.py (path:/tmp/pip-UUoQJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:04:48 INFO - Running setup.py (path:/tmp/pip-GKCbMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:04:48 INFO - Running setup.py (path:/tmp/pip-lz7h0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:04:48 INFO - Running setup.py (path:/tmp/pip-2xN7HW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:04:48 INFO - Running setup.py (path:/tmp/pip-e6JM1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:04:48 INFO - Running setup.py (path:/tmp/pip-u9yzXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:04:48 INFO - Running setup.py (path:/tmp/pip-d8m1T2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:04:48 INFO - Running setup.py (path:/tmp/pip-KB31IE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:04:49 INFO - Running setup.py (path:/tmp/pip-YZSWA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:49 INFO - Running setup.py (path:/tmp/pip-xMwO3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:04:49 INFO - Running setup.py (path:/tmp/pip-Ab8UQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:04:49 INFO - Running setup.py (path:/tmp/pip-ps39A7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:04:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:04:49 INFO - Running setup.py install for manifestparser 16:04:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:04:49 INFO - Running setup.py install for mozcrash 16:04:49 INFO - Running setup.py install for mozdebug 16:04:49 INFO - Running setup.py install for mozdevice 16:04:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:04:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:04:49 INFO - Running setup.py install for mozfile 16:04:49 INFO - Running setup.py install for mozhttpd 16:04:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:04:49 INFO - Running setup.py install for mozinfo 16:04:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Running setup.py install for mozInstall 16:04:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Running setup.py install for mozleak 16:04:50 INFO - Running setup.py install for mozlog 16:04:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Running setup.py install for moznetwork 16:04:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Running setup.py install for mozprocess 16:04:50 INFO - Running setup.py install for mozprofile 16:04:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Running setup.py install for mozrunner 16:04:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:04:50 INFO - Running setup.py install for mozscreenshot 16:04:50 INFO - Running setup.py install for moztest 16:04:51 INFO - Running setup.py install for mozversion 16:04:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:04:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:04:51 INFO - Cleaning up... 16:04:51 INFO - Return code: 0 16:04:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe309dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d419c0>, '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 0x2d428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d42d50>, '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': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', '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 16:04:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:04:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:04:51 INFO - Using env: {'DISPLAY': ':0', 16:04:51 INFO - 'HOME': '/home/cltbld', 16:04:51 INFO - 'LANG': 'en_US.UTF-8', 16:04:51 INFO - 'LANGUAGE': 'en_US:en', 16:04:51 INFO - 'LOGNAME': 'cltbld', 16:04:51 INFO - 'MAIL': '/var/mail/cltbld', 16:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:51 INFO - 'MOZ_NO_REMOTE': '1', 16:04:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:51 INFO - 'NO_EM_RESTART': '1', 16:04:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:51 INFO - 'PWD': '/builds/slave/test', 16:04:51 INFO - 'SHELL': '/bin/bash', 16:04:51 INFO - 'SHLVL': '1', 16:04:51 INFO - 'TERM': 'linux', 16:04:51 INFO - 'TMOUT': '86400', 16:04:51 INFO - 'USER': 'cltbld', 16:04:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', 16:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:51 INFO - '_': '/tools/buildbot/bin/python'} 16:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:04:51 INFO - Running setup.py (path:/tmp/pip-qcoKZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:04:51 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)) 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:04:51 INFO - Running setup.py (path:/tmp/pip-QyVwMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:04:51 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)) 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:04:51 INFO - Running setup.py (path:/tmp/pip-FSpeBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:04:51 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)) 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:04:51 INFO - Running setup.py (path:/tmp/pip-YrkTRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:04:51 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)) 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:04:51 INFO - Running setup.py (path:/tmp/pip-U8lXww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:04:51 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)) 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:04:51 INFO - Running setup.py (path:/tmp/pip-QnJPa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:04:51 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)) 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:04:51 INFO - Running setup.py (path:/tmp/pip-eJIHJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:04:51 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)) 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:04:51 INFO - Running setup.py (path:/tmp/pip-twm8H_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:04:51 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)) 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:04:51 INFO - Running setup.py (path:/tmp/pip-S10Fmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:04:52 INFO - Running setup.py (path:/tmp/pip-Hf7jG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:04:52 INFO - Running setup.py (path:/tmp/pip-bKcWGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:04:52 INFO - Running setup.py (path:/tmp/pip-hkGE4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:04:52 INFO - Running setup.py (path:/tmp/pip-S_x2ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:04:52 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)) 16:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:04:52 INFO - Running setup.py (path:/tmp/pip-XATBSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:52 INFO - Running setup.py (path:/tmp/pip-InWl0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:52 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)) 16:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:04:52 INFO - Running setup.py (path:/tmp/pip-Z7clyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:04:52 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)) 16:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:04:52 INFO - Running setup.py (path:/tmp/pip-lzAT1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:04:52 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)) 16:04:52 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)) 16:04:52 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)) 16:04:52 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)) 16:04:52 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)) 16:04:52 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)) 16:04:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:04:52 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 16:04:52 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 16:04:52 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 16:04:52 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 16:04:52 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 16:04:52 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 16:04:54 INFO - Downloading blessings-1.6.tar.gz 16:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:04:54 INFO - Installing collected packages: blessings 16:04:54 INFO - Running setup.py install for blessings 16:04:54 INFO - Successfully installed blessings 16:04:54 INFO - Cleaning up... 16:04:54 INFO - Return code: 0 16:04:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:04:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:04:54 INFO - Reading from file tmpfile_stdout 16:04:54 INFO - Current package versions: 16:04:54 INFO - argparse == 1.2.1 16:04:54 INFO - blessings == 1.6 16:04:54 INFO - blobuploader == 1.2.4 16:04:54 INFO - docopt == 0.6.1 16:04:54 INFO - manifestparser == 1.1 16:04:54 INFO - mozInstall == 1.12 16:04:54 INFO - mozcrash == 0.17 16:04:54 INFO - mozdebug == 0.1 16:04:54 INFO - mozdevice == 0.48 16:04:54 INFO - mozfile == 1.2 16:04:54 INFO - mozhttpd == 0.7 16:04:54 INFO - mozinfo == 0.9 16:04:54 INFO - mozleak == 0.1 16:04:54 INFO - mozlog == 3.2 16:04:54 INFO - moznetwork == 0.27 16:04:54 INFO - mozprocess == 0.22 16:04:54 INFO - mozprofile == 0.28 16:04:54 INFO - mozrunner == 6.11 16:04:54 INFO - mozscreenshot == 0.1 16:04:54 INFO - mozsystemmonitor == 0.3 16:04:54 INFO - moztest == 0.7 16:04:54 INFO - mozversion == 1.4 16:04:54 INFO - psutil == 3.1.1 16:04:54 INFO - requests == 1.2.3 16:04:54 INFO - wsgiref == 0.1.2 16:04:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:54 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 0x7fbe309dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d419c0>, '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 0x2d428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d42d50>, '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': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', '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 16:04:54 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 16:04:54 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 16:04:54 INFO - Using env: {'DISPLAY': ':0', 16:04:54 INFO - 'HOME': '/home/cltbld', 16:04:54 INFO - 'LANG': 'en_US.UTF-8', 16:04:54 INFO - 'LANGUAGE': 'en_US:en', 16:04:54 INFO - 'LOGNAME': 'cltbld', 16:04:54 INFO - 'MAIL': '/var/mail/cltbld', 16:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:54 INFO - 'MOZ_NO_REMOTE': '1', 16:04:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:54 INFO - 'NO_EM_RESTART': '1', 16:04:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:54 INFO - 'PWD': '/builds/slave/test', 16:04:54 INFO - 'SHELL': '/bin/bash', 16:04:54 INFO - 'SHLVL': '1', 16:04:54 INFO - 'TERM': 'linux', 16:04:54 INFO - 'TMOUT': '86400', 16:04:54 INFO - 'USER': 'cltbld', 16:04:54 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', 16:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:54 INFO - '_': '/tools/buildbot/bin/python'} 16:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:04:55 INFO - Cleaning up... 16:04:55 INFO - Return code: 0 16:04:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe309dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d419c0>, '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 0x2d428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d42d50>, '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': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', '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 16:04:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:04:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:04:55 INFO - Using env: {'DISPLAY': ':0', 16:04:55 INFO - 'HOME': '/home/cltbld', 16:04:55 INFO - 'LANG': 'en_US.UTF-8', 16:04:55 INFO - 'LANGUAGE': 'en_US:en', 16:04:55 INFO - 'LOGNAME': 'cltbld', 16:04:55 INFO - 'MAIL': '/var/mail/cltbld', 16:04:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:55 INFO - 'MOZ_NO_REMOTE': '1', 16:04:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:55 INFO - 'NO_EM_RESTART': '1', 16:04:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:55 INFO - 'PWD': '/builds/slave/test', 16:04:55 INFO - 'SHELL': '/bin/bash', 16:04:55 INFO - 'SHLVL': '1', 16:04:55 INFO - 'TERM': 'linux', 16:04:55 INFO - 'TMOUT': '86400', 16:04:55 INFO - 'USER': 'cltbld', 16:04:55 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', 16:04:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:55 INFO - '_': '/tools/buildbot/bin/python'} 16:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:55 INFO - Downloading/unpacking jsonschema 16:04:55 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 16:04:55 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 16:04:55 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 16:04:55 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 16:04:55 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 16:04:55 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 16:04:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:04:57 INFO - Downloading/unpacking functools32 (from jsonschema) 16:04:57 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 16:04:57 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 16:04:57 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 16:04:57 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 16:04:57 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 16:04:57 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 16:04:57 INFO - Downloading functools32-3.2.3-2.tar.gz 16:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:04:57 INFO - warning: no files found matching '*.txt' 16:04:57 INFO - no previously-included directories found matching 'build' 16:04:57 INFO - no previously-included directories found matching 'dist' 16:04:57 INFO - no previously-included directories found matching '.git*' 16:04:57 INFO - Installing collected packages: jsonschema, functools32 16:04:57 INFO - Running setup.py install for functools32 16:04:57 INFO - warning: no files found matching '*.txt' 16:04:57 INFO - no previously-included directories found matching 'build' 16:04:57 INFO - no previously-included directories found matching 'dist' 16:04:57 INFO - no previously-included directories found matching '.git*' 16:04:57 INFO - Successfully installed jsonschema functools32 16:04:57 INFO - Cleaning up... 16:04:57 INFO - Return code: 0 16:04:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:04:57 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')]} 16:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:57 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')]} 16:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe309dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2be78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d419c0>, '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 0x2d428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d42d50>, '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': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', '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 16:04:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:04:57 INFO - Using env: {'DISPLAY': ':0', 16:04:57 INFO - 'HOME': '/home/cltbld', 16:04:57 INFO - 'LANG': 'en_US.UTF-8', 16:04:57 INFO - 'LANGUAGE': 'en_US:en', 16:04:57 INFO - 'LOGNAME': 'cltbld', 16:04:57 INFO - 'MAIL': '/var/mail/cltbld', 16:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:57 INFO - 'MOZ_NO_REMOTE': '1', 16:04:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:57 INFO - 'NO_EM_RESTART': '1', 16:04:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:57 INFO - 'PWD': '/builds/slave/test', 16:04:57 INFO - 'SHELL': '/bin/bash', 16:04:57 INFO - 'SHLVL': '1', 16:04:57 INFO - 'TERM': 'linux', 16:04:57 INFO - 'TMOUT': '86400', 16:04:57 INFO - 'USER': 'cltbld', 16:04:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', 16:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:57 INFO - '_': '/tools/buildbot/bin/python'} 16:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:57 INFO - Downloading/unpacking flake8 16:04:57 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 16:04:57 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 16:04:57 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 16:04:57 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 16:04:57 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 16:04:57 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 16:04:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:04:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:04:59 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 16:04:59 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 16:04:59 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 16:04:59 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 16:04:59 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 16:04:59 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 16:05:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:05:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:05:00 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 16:05:00 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 16:05:00 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 16:05:00 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 16:05:00 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 16:05:00 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 16:05:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:05:00 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 16:05:00 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 16:05:00 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 16:05:00 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 16:05:00 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 16:05:00 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 16:05:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:05:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:05:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:05:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:05:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:05:00 INFO - no previously-included directories found matching 'docs/_build' 16:05:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:05:00 INFO - Running setup.py install for pep8 16:05:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:05:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:05:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:05:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:05:00 INFO - no previously-included directories found matching 'docs/_build' 16:05:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:05:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:05:00 INFO - Cleaning up... 16:05:00 INFO - Return code: 0 16:05:00 INFO - Running post-action listener: _resource_record_post_action 16:05:00 INFO - Running post-action listener: _start_resource_monitoring 16:05:00 INFO - Starting resource monitoring. 16:05:00 INFO - ##### 16:05:00 INFO - ##### Finished create-virtualenv step (success) 16:05:00 INFO - ##### 16:05:00 INFO - ##### 16:05:00 INFO - ##### Running install step. 16:05:00 INFO - ##### 16:05:00 INFO - Running pre-action listener: _resource_record_pre_action 16:05:00 INFO - Running main action method: install 16:05:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:05:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:05:01 INFO - Reading from file tmpfile_stdout 16:05:01 INFO - Detecting whether we're running mozinstall >=1.0... 16:05:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:05:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:05:01 INFO - Reading from file tmpfile_stdout 16:05:01 INFO - Output received: 16:05:01 INFO - Usage: mozinstall [options] installer 16:05:01 INFO - Options: 16:05:01 INFO - -h, --help show this help message and exit 16:05:01 INFO - -d DEST, --destination=DEST 16:05:01 INFO - Directory to install application into. [default: 16:05:01 INFO - "/builds/slave/test"] 16:05:01 INFO - --app=APP Application being installed. [default: firefox] 16:05:01 INFO - mkdir: /builds/slave/test/build/application 16:05:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:05:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:05:17 INFO - Reading from file tmpfile_stdout 16:05:17 INFO - Output received: 16:05:17 INFO - /builds/slave/test/build/application/firefox/firefox 16:05:17 INFO - Running post-action listener: _resource_record_post_action 16:05:17 INFO - ##### 16:05:17 INFO - ##### Finished install step (success) 16:05:17 INFO - ##### 16:05:17 INFO - ##### 16:05:17 INFO - ##### Running run-tests step. 16:05:17 INFO - ##### 16:05:17 INFO - Running pre-action listener: _resource_record_pre_action 16:05:17 INFO - Running main action method: run_tests 16:05:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:05:17 INFO - Python 2.7.3 16:05:17 INFO - Return code: 0 16:05:17 INFO - grabbing minidump binary from tooltool 16:05: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')]} 16:05:17 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 0x2d419c0>, '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 0x2d428c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d42d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:05:17 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 16:05:17 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 16:05:17 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 16:05:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:05:17 INFO - Return code: 0 16:05:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:05:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:05:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:05:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:05:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:05:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:05:18 INFO - Return code: 0 16:05:18 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/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 16:05:18 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/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 16:05:18 INFO - Using env: {'DISPLAY': ':0', 16:05:18 INFO - 'HOME': '/home/cltbld', 16:05:18 INFO - 'LANG': 'en_US.UTF-8', 16:05:18 INFO - 'LANGUAGE': 'en_US:en', 16:05:18 INFO - 'LOGNAME': 'cltbld', 16:05:18 INFO - 'MAIL': '/var/mail/cltbld', 16:05:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:05:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:18 INFO - 'MOZ_NO_REMOTE': '1', 16:05:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:18 INFO - 'NO_EM_RESTART': '1', 16:05:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:18 INFO - 'PWD': '/builds/slave/test', 16:05:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:05:18 INFO - 'SHELL': '/bin/bash', 16:05:18 INFO - 'SHLVL': '1', 16:05:18 INFO - 'TERM': 'linux', 16:05:18 INFO - 'TMOUT': '86400', 16:05:18 INFO - 'USER': 'cltbld', 16:05:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910', 16:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:18 INFO - '_': '/tools/buildbot/bin/python'} 16:05:18 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/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 16:05:18 INFO - mozversion application_buildid: 20160529133059 16:05:18 INFO - mozversion application_changeset: 0c174794580dcc4d40eace91fed1bdbd87491b2e 16:05:18 INFO - mozversion application_display_name: Nightly 16:05:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:05:18 INFO - mozversion application_name: Firefox 16:05:18 INFO - mozversion application_remotingname: firefox 16:05:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:05:18 INFO - mozversion application_vendor: Mozilla 16:05:18 INFO - mozversion application_version: 49.0a1 16:05:18 INFO - mozversion platform_buildid: 20160529133059 16:05:18 INFO - mozversion platform_changeset: 0c174794580dcc4d40eace91fed1bdbd87491b2e 16:05:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:05:18 INFO - mozversion platform_version: 49.0a1 16:05:18 INFO - using testdate: 1464563118 16:05:18 INFO - actual date: 1464563118 16:05:18 INFO - starting webserver on 'localhost:36490' 16:05:18 INFO - SUITE-START | Running 2 tests 16:05:18 INFO - TEST-START | dromaeo_css 16:05:18 INFO - operating with platform_type : linux_ 16:05:18 INFO - Initialising browser for dromaeo_css test... 16:05:18 INFO - TEST-INFO | started process 14733 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwZXZUs/profile http://localhost:36490/getInfo.html) 16:05:25 INFO - PROCESS | 14733 | __metrics Screen width/height:1600/1200 16:05:25 INFO - PROCESS | 14733 | colorDepth:24 16:05:25 INFO - PROCESS | 14733 | Browser inner width/height: 1024/697 16:05:25 INFO - PROCESS | 14733 | __metrics 16:05:26 ERROR - PROCESS | 14733 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:05:27 INFO - TEST-INFO | 14733: exit 0 16:05:27 INFO - Browser initialized. 16:05:27 INFO - Running cycle 1/1 for dromaeo_css test... 16:05:27 INFO - TEST-INFO | started process 14817 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwZXZUs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:05:39 INFO - PROCESS | 14817 | RSS: Main: 156585984 16:05:39 INFO - PROCESS | 14817 | 16:06:30 INFO - PROCESS | 14817 | 1464563190674 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] 16:08:39 INFO - PROCESS | 14817 | Cycle 1(1): loaded http://localhost:36490/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36490/tests/dromaeo/cssquery-ext.html) 16:08:40 INFO - PROCESS | 14817 | RSS: Main: 247648256 16:08:40 INFO - PROCESS | 14817 | 16:11:42 INFO - PROCESS | 14817 | Cycle 1(1): loaded http://localhost:36490/tests/dromaeo/cssquery-ext.html (next: http://localhost:36490/tests/dromaeo/cssquery-jquery.html) 16:11:43 INFO - PROCESS | 14817 | RSS: Main: 249126912 16:11:43 INFO - PROCESS | 14817 | 16:14:45 INFO - PROCESS | 14817 | Cycle 1(1): loaded http://localhost:36490/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36490/tests/dromaeo/cssquery-mootools.html) 16:14:45 INFO - PROCESS | 14817 | RSS: Main: 250499072 16:14:45 INFO - PROCESS | 14817 | 16:17:47 INFO - PROCESS | 14817 | Cycle 1(1): loaded http://localhost:36490/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36490/tests/dromaeo/cssquery-prototype.html) 16:17:48 INFO - PROCESS | 14817 | RSS: Main: 251199488 16:17:48 INFO - PROCESS | 14817 | 16:20:51 INFO - PROCESS | 14817 | Cycle 1(1): loaded http://localhost:36490/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36490/tests/dromaeo/cssquery-yui.html) 16:20:51 INFO - PROCESS | 14817 | RSS: Main: 214614016 16:20:51 INFO - PROCESS | 14817 | 16:23:54 INFO - PROCESS | 14817 | Cycle 1(1): loaded http://localhost:36490/tests/dromaeo/cssquery-yui.html (next: http://localhost:36490/tests/dromaeo/cssquery-dojo.html) 16:23:54 INFO - PROCESS | 14817 | RSS: Main: 276447232 16:23:54 INFO - PROCESS | 14817 | 16:23:54 INFO - PROCESS | 14817 | __start_tp_report 16:23:54 INFO - PROCESS | 14817 | _x_x_mozilla_page_load 16:23:54 INFO - PROCESS | 14817 | _x_x_mozilla_page_load_details 16:23:54 INFO - PROCESS | 14817 | |i|pagename|runs| 16:23:54 INFO - PROCESS | 14817 | |0;dojo.html;3105.9378812423765;3121.3913286909055;3137.074563003914;3144.072498612909;3173.9523907141397;5202.687838729669;5271.288376069229;5345.33183352081;5356.196570514425;5361.356637203541;5311.521963023327;5331.306930099082;5334.386545547263;5385.919211211825;5427.213054107152;4745.145873742734;4831.444383895861;4843.564079232869;4870.561649451549;4963.280324352967;4861.465238823745;4883.365162528867;4901;4917.09033828741;4918.409790825106;4674.532546745315;4774.212254977924;4842.515748425165;4843.515648435164;4846.200376937801;4826.276058591205;4850.151223535867;4857.927131093001;4901.191303434929;4954.87612809679;4857.465678775332;4875.049365373754;4880.902381952341;4882.43851957026;4888.633352498541;5134.460881607452;5244.685318880879;5319.468053194688;5345.973270133624;5404.378496472917;5874.3244526879525;5902.291724992985;5927.199828023205;5930.288365396137;5941.643501389931;5580.079286917654;5724.227229324071;5760.222369980041;5808.273965754281;5808.941910580925;5411.080116380185;5416.4312747161375;5435;5435.103207970719;5435.402105768362;5924.170616113747;5937.52499800015;5939.3763654816485;5942;5944.583879128506;5510.779568817283;5600;5667.943320566824;5700.087985922315;5710.628809127394;5797.24635797352;5811.331696854916;5840.328362238312;5845.064789633639;5846.912296315559;5758.654480731169;5770.134479827975;5773.480386765212;5773.538116950634;5792.739326730307;5505.339359276913;5689.431056894277;5795.072788353843;5810.244668193192;5810.825675229666;4367.344898265285;4370.672199585044;4394.450693663292;4407.537208593116;4434.290513517822;3884.028992751812;3903.316919539126;3922.3135951208424;3929.6070392960473;3956.4263181838473;5366.517013468807;5389.056915039931;5430.8913821723345;5508.641938274;5536.833894983157;5753.079507278815;5791.218185544982;5818.0981947798155;5824.767009319579;5871.500922421556;5494.697791621499;5503.064479038493;5508.283923089973;5510.393856675762;5510.586705997066;4732.763361831963;4805.687630304019;4824.782884770194;4858.1012512685265;4913.754312284372;5970.761169553256;5971.253593300838;5975.223220981245;5985.46130848217;6023.789167379121;5830.95042892275;5840.503557197686;5840.853978650398;5843.8539036522725;5846.064629659234;5774.1916131740745;5828.271466066742;5832.387599302182;5833.533317334688;5974.790882319098;5613.607047506635;5614.494695477427;5616.21373007771;5619.241402410622;5621.9156712650165;5654.830355089354;5706.857328566819;5707.942920570741;5717.42825717425;5718.885622287614;5535.474129957647;5553.4724201200825;5607.971960140173;5637.46444087811;5670.121131224667;5632.774689012557;5635.323761148688;5636.887262254814;5641.26663533738;5645.548356131581;5372.5970552207955;5535.640183388067;5538.695371754592;5547.306586676666;5547.918155959549;5579.665220086646;5622.24099746529;5646.265985421869;5769.59612827098;5777.884442311046;5510.201020851874;5516.806911758069;5517.613767036269;5562.749676264497;5583.050881350261 16:23:54 INFO - PROCESS | 14817 | |1;ext.html;638.4509321983094;643.3727116061839;649.6102338596843;652.3378770547897;657.8454063295122;31051.68948310516;33671.66328336716;43054.99999999996;44069.55930440695;44178.116437671306;45051;45586.7720661397;45588;45647.543524564746;45706.31440528395;8375.874361884562;8521.190486903764;8527.891374121356;8536.231739143475;8537.957310213471;8618.224359807617;8757.25563327118;8765.517896515688;8775.385722999392;8776.07851175627;8755.518446485441;8815.955920220389;8817.691380801672;8818.20636142747;8820.514871682055;8753.474791512497;8782.868256976151;8801.999999999967;8802.735917922473;8805.911940880573;8657.783555411102;8750.781230469289;8765.123487651184;8768.780780480476;8770;35396.11509712252;35463.64536354629;35567.11082222939;35720.64279357199;35773.46339804905;3268.6894744999186;3457.7060949819297;3459.757816952814;3480.2343484433386;3587.3004764071015;31721.524177137362;31835;32131.977176879187;32355.35289294201;32624;4213.494380674308;4224.387463817729;4246.57534246576;4254.638355739766;4328.026194106301;337819;338536;339159;339622;340445;57421.71289143527;57695;58220.417795822344;58282;58336.41663583395;60731;60968.78062438726;61085.08372374418;61194.694026529585;61300.38699613036;14202.715945681028;14364.138151710931;14398.856011439751;14404.855951440351;14409.567712968417;11924.046076313874;11940.283582985048;11956.043516518832;11988.820167697491;11989.820152697717;15974;16115.19424028794;16153.353865845469;16241.51275461738;16379.672406552041;5704.71476426177;5773.653580785082;5775.6823374714795;5862.530997520188;5866.078027927002;1004.2719028704131;1011.0950699124243;1046.1473898772476;1084.6312253833576;1137.7895089408487;31877.20306992344;32155.35689286248;32170.839145804122;32238.838805805823;32459.51310730341;1071.8285074388216;1084.5770149641646;1108.534415545473;1117.63118171005;1191.5055252070467;16206.189690515426;16668.74996875048;16684;16795.412160574564;16983.575410614834;137051;137816.31091844678;137878;138358;138825;58539;58653.12020319699;58829;58909.705451472466;58913.70543147257;1239.727260002816;1326.1247576599399;1332.9600111996656;1356.7286542691502;1386.750384930702;27939.720602794525;29119.708802912548;29140.271493211992;29643.40713185681;29701.702982969895;49640.25539616981;49647.751761240965;50111.74944125256;50147.74926125492;50227.74886125546;385732;387349;388113;388487;388750;40629.796851015555;40816.79591602023;41013.58986410217;41061.38407923943;42266.1546769069;47793.76103119462;48421.5157848417;48654.513454866414;49324.506754931994;49623.50376496189;39976.40035399413;41178.1764364717;41193.176136477705;41782.164356712085;42633.78683106565;39964.2007159861;40331.79834100811;40379.59620403758;41205.793971029954;41227;40832.79583602182;41356.79321603373;41783.58216417797;41828.53600123981;41875.162496750505;33538.66461335453;35966;36039.45940810838;36963.44554831626;36983.63016369802;27809;27912.30219244535;28022.299442513286;28068.85965570159;28073.859630701714 16:23:54 INFO - PROCESS | 14817 | |2;jquery.html;357.69059763304716;376.53497930056375;377.73369774309117;390.73625302920533;401.1255463090462;15413.922930385346;17383.13084345784;18706.719399209007;19458.90270548649;19806.30677926273;16588.08765517896;17073.57316067099;17195.226214820334;17202.569935751613;17304;5418.187271909215;5552.805651802188;5563.054280772259;5585.944140558603;5604.607677462579;5518.475744804237;6911.619860907648;7287.198408175096;7293.124825021005;7302.598357090358;7250.238724933885;7252.166000909884;7263.418926485869;7291.380232680229;7375.852482950338;6986.371226589631;7050.647467626598;7088.326608972191;7391.667374968112;7407.703691852319;6888.724451021953;7044.6125463100025;7072.434205263567;7074.3633073023675;7077.5399599025895;24556.64521146919;25125.24624261275;25703;25920.8703956479;26477.205683829518;2277.6697378880135;2315.3401280635107;2369.8104151667826;2387.1048356866177;2572.3826281692445;15116.622084447867;15234.619134521614;15583;15689.293981770848;16050.75923861143;1933.1204302042677;2081.8438617103625;2110.525131845338;2226.5546890621777;2337.440502649596;94735;97174.05651886994;99099;100157.4992125042;101009;17592;17606.23937606255;17754;17939.820601794076;18226.544336391697;32237.194070148435;32424.675753242638;32512.67487325144;32635.836820816217;32657.754491915897;9747;9865.852012219822;9929.65246216379;9997.900020999843;10138.847917281246;8183.918160818315;8205.71280005197;8324.66701331952;8370.455920365157;8496.575171241413;8730.257928076184;8879.289656827568;8912.55437228136;8960.938984033579;9077.319201059947;3151.2909595340866;3294.637589865113;3338.7996720196866;3510.8238740021866;3569.3016238535897;740.5149626994328;859.4113032572708;959.3524371049514;964.3683387381277;992.2508506077864;19155.80844191568;19503.80496195048;19516.12177452018;19704.635443746844;19809.603807923762;954.9570269337896;963.2967933408651;986.487026746088;1026.3892983674702;1081.605214096849;10270.640527581649;10389.584416623251;10677;10748.247622666338;10753.623623173151;492736;497691;499949.5012624641;512705;513690;56370.43629563652;60148.39851601516;60750.39249607448;98303.10181388997;100788.49605752071;973.454865275448;1002.1581871228203;1016.867807185076;1020.5152552537485;1022.9335093219066;17400.303987840853;17559.368264918532;17654.911725441292;17798.288068477636;17809.376671816422;41066;42191;42278.788606056776;42470.78764606157;42858.357124642534;56971.43028569661;59054;59220;59371.10943335767;60014.699926500085;5674.659520428788;6576.671166441754;7963.243491868263;8177.550234737147;8351.95824020876;12631.494740210655;12677.41435901033;12849.421776020288;12943.870561294643;12982.480700771863;11381.487833047699;11706.824397634213;11769.352685602375;11855.940720296343;11969.94015029954;11300;11479.139064570358;11694.941525292319;11740.295582265091;11904.761904761683;11314.264572802742;11962.342071186169;12039.75920481568;12142.210756300812;12310.569130080405;8275.055361989807;8286.295664868554;8286.627101780314;8414.158584141536;8421.410501265098;11673.299602023906;11794.056475482112;11924.165308428326;11943.761124777282;11974.880251197725 16:23:54 INFO - PROCESS | 14817 | |3;mootools.html;1299.2529295655002;1539.6074001129714;1548.401248292571;1717.7251639737638;1861.0601646168675;8591.183837535433;8928;8934.463932164066;9115.68095116671;9137.406068605536;8936.508492032935;9104.499252541107;9112.772180695485;9130.269578433727;9151.267898568116;2018.8384929205702;2201.8128459081;2211.7898799614013;2231.4867580456503;2297.1041293895396;2300.4133945843787;2308.53829234153;2309.584274830538;2313.0400883633274;2313.699219101523;2288.5079707862806;2316.2587971849016;2318.8492747971413;2331.160782118436;2336.5794157051723;2138.0699395762817;2138.8395870309632;2140.743110826695;2146.774588668183;2151.2470635277605;2282.2240438251074;2304.953900921972;2312.2716344351475;2322.094383190557;2329.895154718025;8989.280857531381;9022.323325750529;9074.546272686339;9075.410098343587;9097.408668436598;4886.560209581155;5006.5744411725045;5014.974925125351;5015.6238282128625;5016.07202667504;3142.1516190628404;3259.429599820022;3277.4100661880852;3292.111129994912;3343.130785995634;1051.9526621301984;1058.9099926506256;1068.4444089073638;1077.7413420778958;1093.0981939899534;13024.609261722164;13030.609081727564;13051.673708157277;13074.934625326907;13084.280364579945;4362.716423432466;4523.389342438729;4602.3786788783755;4614;4628.120657075145;10752.247342685936;10771.676849694522;10780.514876830404;10793.892061079447;10794.028537431668;4705.576498115118;5032.7735251913755;5043.142665746832;5051;5052.115879721107;4356.237658409766;4539.7049191802425;4571.657125715585;4574.290984897346;4591.127685739699;3965.1276719121747;3979.403089536535;3986.2390197234363;3989.84040638377;4008.4567441534637;1062.824633935392;1164.8776878427811;1192.9164958452825;1217.6164508179895;1241.472374240944;842.3598065470164;885.4156256870434;995.2679893691718;995.3430735714394;1014.3457469931852;5926.940730592639;5992.40075992406;6030.48740857032;6117.449429551361;6183.2400337576055;912.9406588571721;947.5167664491021;961.6586111930304;967.9758006049905;1003.8054714889258;4044.9595504045174;4098.979505102515;4141.295979683401;4156.501219853637;4167.541570427251;11273.943630281796;11531.942340288246;11655;11661.18371713989;11675.649730508097;11298.830517542243;11327.320360778214;11385.316880987168;11403.087752979907;11408.657740267805;8066.878996815052;8375.329973602098;8377.120402357787;8611.698590549426;8634.481931084156;6265.906011409924;6283.3088360281245;6375.904361434675;6419.069234960998;6468.8706225874275;10370.740731481472;10553.788924221626;10800.945995269973;10827.296225745302;10830;10678.199135064746;10815.459227038775;10829.29609575375;10953;11056.391898445663;7833.647485863035;7905.802354940943;7909.485883417559;8117.391195660467;8152.144024877302;8413.70552030676;8465.703700370457;8481.872771908304;8485.703000394957;8539.188777066169;7580.241975802375;7735.342495887912;7754.030746156731;7841.647125879464;8005.799855003672;7517.962410187914;7617.69529218825;7689.077310722749;7745.225477452435;7763;6761.898571521529;6771.62756048422;6829.624370659462;6831.760888368884;7001.404880585207;5245.160774276097;5301.867453313698;5310.415854256106;5311.282976798083;5352.437994010573;5280.735963201748;5328.600354973315;5345.705986170798;5353.063213937623;5477.945220547744 16:23:54 INFO - PROCESS | 14817 | |4;prototype.html;302.8228486335495;308.57570840094866;317.1358049315614;317.4222914295981;318.2536950850255;5008;5100.668456550322;5143.199880041983;5216.817411390597;5250.921236181455;5129.461406552315;5141.691498510092;5154.020736060155;5169.844904652857;5177.705573606599;4021.6125436724355;4060.817263223147;4061.878143655695;4077.225327187825;4090.9181816363803;3828.8659896903614;3842.4812650292133;4058.614431629005;4064.4512990746316;4093.2427500912277;4027.597240275978;4044.1102957349485;4059.3910913363084;4078.449409329747;4080.3879418087226;4025.9798701006453;4038.4749982502235;4039.171969746195;4041.7066538707627;4042.292598795199;3495.755297129202;3578.9284214315567;3602.3875941090078;3605.369060414417;3625.5105560749225;4946.812764936425;4947.406311242638;4957.206846904514;4968.403791545002;4975.278584605212;4576.26779715244;4578.748168850712;4586.7477288749105;4586.830358258656;4616.422947131608;5235.842924712265;5331.706756128411;5377.919331210035;5378.569714422837;5395.406505284415;3855.8072096395067;3893.10458594522;3896.883093507199;3902.4731661225937;3908.921821563581;5544.140658197945;5571.359293681197;5596.244506991545;5598.468145526169;5638.802641907555;3872.9225415491533;3968.8015599219925;3993.3011722948368;4009.278329900646;4020.647920966209;4029.6171863672903;4033.092554175287;4033.3143365627916;4043.4945631796027;4047.5142982841658;4587.8623641290815;4595.4954549770455;4599.954000459952;4606.170889239902;4617.746023968714;5352.839414817562;5433.402325744165;5441.891162176735;5459.2629994950175;5478.726063696799;1376.4356613788298;1378.5519706095558;1380.82049333588;1384.3286006286942;1392.4430227908715;819.0703551469126;847.7541512961295;851.3784936996028;859.4113032572708;866.9089745576749;4757.26262429324;4793.616510679138;5093.796248150106;5113.233015047698;5255.474452554714;5545.584081193927;5549.361823390362;5604.570834437192;5610.298712660918;5624.634398764068;5268.789248430096;5304.549113325334;5311.256424100629;5329.307190065267;5335.466453354711;2807.368342123006;2987.850607469618;2987.893535057779;2993.75759059992;2997.370552184022;5777.393373695785;5790;5792.797252096157;5799.18811366409;5818.516278348994;5372.5970552207955;5656.095024796013;5672.489475947102;5682.289713785777;5753.971230143822;5722.62656962326;5743.396943320975;5753.971230143906;5782.595218334676;5790.812883358902;5557.749901254534;5589.664620122806;5618.52242559387;5635.267415235993;5640.32316122068;5492.615516913777;5564.721763911869;5576.553875690016;5583.916241256303;5597.748101335368;5756.94243057564;5788.21859049023;5790.449907258803;5796.333421656564;5805.593608447537;3908.863189788344;3917.6082391760597;3922.666573341298;3952.2095580883365;3957.4657421248917;5382.407935127055;5636.605437619492;5643.1535269709875;5646.265985422033;5685.289338832646;5494.587905906993;5495.917561236664;5531.585131115263;5531.8893622126525;5553.194786755978;5249.480249480194;5498.560115190855;5547.778088876561;5558.638688485235;5575.749091290821;5385.299911011543;5402.378726446509;5410.997930765642;5425.83722488326;5431.34823821144;5241.47585241488;5406.540444062299;5474.781008759605;5517.420670829505;5540.529055030368;5322.228276899906;5346.091164501966;5356.491133342326;5362.222477740784;5362.410134885237 16:23:54 INFO - PROCESS | 14817 | |5;yui.html;644.8581312111337;650.3886346833979;661.9172603424572;720.7693538067817;743.6207534157579;13506.662333441669;13587.320633968311;13598;13998.79001814971;14550;13585.184888906673;13746.725065498684;13771.036027478081;13777.380017899193;13788.241646709426;683.3200965039792;787.5668382389691;803.6785285885634;812.0012384766741;822.2928281677753;812.9471584347028;813.8128230506987;816.143049797713;816.3428440105713;816.7998840284139;849.3035710717214;853.4495250563383;853.4751128056232;853.8548446764065;854.1245223645751;704.3625518905399;818.615250832111;842.6039761312173;842.6629348260683;844.7845799321165;613.1171113596407;613.2487702564367;613.6164896939413;614.0053113955377;614.6097228260043;11680.240784348991;11698.064154867676;11719.179657423983;11725.4137293135;11730.648080557597;12885.87114128856;13142.802857957138;13241.271730054843;13271.734565308736;13473.730525389536;10552.894471055344;10582.100521455686;10588.891878693694;10722.678319650424;10859.51132199045;4281.871543853689;4311.611954924072;4403.823847046115;4520.728756274634;4533.909321813579;59509.7024514879;60115.39884601142;60141;60889.6955515224;61187;10317.587296508205;10474.371537707762;10478.842817357745;10495.26533142688;10716.35701857891;17477.300907963792;17648.47054588339;17689;17730.91134544319;17794.644107117787;7564.130125035691;7733.49732267412;7872.803179920548;8004.279614834576;8126.065502467174;4297.828086876552;4368.650507959355;4377.584129507692;4395.912081758411;4597.816087356535;3249.2201871550656;3309.834508274577;3329.1011426914597;3331.0506505645776;3337.732981361485;612.1643956000116;614.241411856357;618.3816183816184;621.7481919822479;643.3373625166087;585.4379795396381;587.8177764892897;611.2817439508559;707.6320313437087;721.2607077745248;8411.369147313975;8447.113053129457;8714.782130446789;8767.210951014311;8805.295576353876;606.099812397675;614.2720875762236;618.1407843098098;648.45205801098;664.3489380407132;4058.3100872851687;4086.2849001424634;4165.437665915063;4233.7883105844585;4294.613484786384;67980;69520.6523967387;70428;71382;71414.64292678503;59278.40721592729;62234.37765622286;62479.37520624827;62866.37133628697;63935;54030.459695403326;54537.181942270894;54544.72727636416;55190.72404637951;55503;7977.162397948364;8031.799205019921;8129.75610731679;8232.794180145544;8254.958725206336;37968;38097.61527978075;38202.235955280186;38543.614563854004;38548.42177367398;46534.53465465302;50382.49617503925;52257.47742522678;52825.471745282055;53629.46370536245;11153.553857845594;11277.605283815356;11504.36725980079;11793;11831.230969986924;12224.105263684143;12600.30698311565;12783.93608031954;13098;13128.803067953797;12190;12358.876411235773;12445.09151829128;12609.49562017509;12680.6829829255;11773.529058837894;12479;12753.744925101631;12806.679833003876;12967.286799226133;11766.52933882635;12152.331621760535;12231.877681223074;12400.441980110736;12402.25586464815;10266.845997309896;10328.535215915152;10564.471776411303;10669.57321707154;10758.73103172427;10201.38791672502;10509.527071281658;10642.255042147284;10666.73333166677;10682.145428365557 16:23:54 INFO - PROCESS | 14817 | __end_tp_report 16:23:54 INFO - PROCESS | 14817 | __start_cc_report 16:23:54 INFO - PROCESS | 14817 | _x_x_mozilla_cycle_collect,906 16:23:54 INFO - PROCESS | 14817 | __end_cc_report 16:23:54 INFO - PROCESS | 14817 | __startTimestamp1464564234700__endTimestamp 16:23:54 INFO - PROCESS | 14817 | 16:23:54 INFO - PROCESS | 14817 | ------- Summary: start ------- 16:23:54 INFO - PROCESS | 14817 | Number of tests: 6 16:23:54 INFO - PROCESS | 14817 | 16:23:54 INFO - PROCESS | 14817 | [#0] dojo.html Cycles:165 Average:5340.29 Median:5536.83 stddev:618.00 (11.2%) stddev-sans-first:594.52 16:23:54 INFO - PROCESS | 14817 | Values: 3105.9 3121.4 3137.1 3144.1 3174.0 5202.7 5271.3 5345.3 5356.2 5361.4 5311.5 5331.3 5334.4 5385.9 5427.2 4745.1 4831.4 4843.6 4870.6 4963.3 4861.5 4883.4 4901.0 4917.1 4918.4 4674.5 4774.2 4842.5 4843.5 4846.2 4826.3 4850.2 4857.9 4901.2 4954.9 4857.5 4875.0 4880.9 4882.4 4888.6 5134.5 5244.7 5319.5 5346.0 5404.4 5874.3 5902.3 5927.2 5930.3 5941.6 5580.1 5724.2 5760.2 5808.3 5808.9 5411.1 5416.4 5435.0 5435.1 5435.4 5924.2 5937.5 5939.4 5942.0 5944.6 5510.8 5600.0 5667.9 5700.1 5710.6 5797.2 5811.3 5840.3 5845.1 5846.9 5758.7 5770.1 5773.5 5773.5 5792.7 5505.3 5689.4 5795.1 5810.2 5810.8 4367.3 4370.7 4394.5 4407.5 4434.3 3884.0 3903.3 3922.3 3929.6 3956.4 5366.5 5389.1 5430.9 5508.6 5536.8 5753.1 5791.2 5818.1 5824.8 5871.5 5494.7 5503.1 5508.3 5510.4 5510.6 4732.8 4805.7 4824.8 4858.1 4913.8 5970.8 5971.3 5975.2 5985.5 6023.8 5831.0 5840.5 5840.9 5843.9 5846.1 5774.2 5828.3 5832.4 5833.5 5974.8 5613.6 5614.5 5616.2 5619.2 5621.9 5654.8 5706.9 5707.9 5717.4 5718.9 5535.5 5553.5 5608.0 5637.5 5670.1 5632.8 5635.3 5636.9 5641.3 5645.5 5372.6 5535.6 5538.7 5547.3 5547.9 5579.7 5622.2 5646.3 5769.6 5777.9 5510.2 5516.8 5517.6 5562.7 5583.1 16:23:54 INFO - PROCESS | 14817 | 16:23:54 INFO - PROCESS | 14817 | [#1] ext.html Cycles:180 Average:47332.63 Median:40504.70 stddev:81384.90 (200.9%) stddev-sans-first:81537.69 16:23:54 INFO - PROCESS | 14817 | Values: 638.5 643.4 649.6 652.3 657.8 31051.7 33671.7 43055.0 44069.6 44178.1 45051.0 45586.8 45588.0 45647.5 45706.3 8375.9 8521.2 8527.9 8536.2 8538.0 8618.2 8757.3 8765.5 8775.4 8776.1 8755.5 8816.0 8817.7 8818.2 8820.5 8753.5 8782.9 8802.0 8802.7 8805.9 8657.8 8750.8 8765.1 8768.8 8770.0 35396.1 35463.6 35567.1 35720.6 35773.5 3268.7 3457.7 3459.8 3480.2 3587.3 31721.5 31835.0 32132.0 32355.4 32624.0 4213.5 4224.4 4246.6 4254.6 4328.0 337819.0 338536.0 339159.0 339622.0 340445.0 57421.7 57695.0 58220.4 58282.0 58336.4 60731.0 60968.8 61085.1 61194.7 61300.4 14202.7 14364.1 14398.9 14404.9 14409.6 11924.0 11940.3 11956.0 11988.8 11989.8 15974.0 16115.2 16153.4 16241.5 16379.7 5704.7 5773.7 5775.7 5862.5 5866.1 1004.3 1011.1 1046.1 1084.6 1137.8 31877.2 32155.4 32170.8 32238.8 32459.5 1071.8 1084.6 1108.5 1117.6 1191.5 16206.2 16668.7 16684.0 16795.4 16983.6 137051.0 137816.3 137878.0 138358.0 138825.0 58539.0 58653.1 58829.0 58909.7 58913.7 1239.7 1326.1 1333.0 1356.7 1386.8 27939.7 29119.7 29140.3 29643.4 29701.7 49640.3 49647.8 50111.7 50147.7 50227.7 385732.0 387349.0 388113.0 388487.0 388750.0 40629.8 40816.8 41013.6 41061.4 42266.2 47793.8 48421.5 48654.5 49324.5 49623.5 39976.4 41178.2 41193.2 41782.2 42633.8 39964.2 40331.8 40379.6 41205.8 41227.0 40832.8 41356.8 41783.6 41828.5 41875.2 33538.7 35966.0 36039.5 36963.4 36983.6 27809.0 27912.3 28022.3 28068.9 28073.9 16:23:54 INFO - PROCESS | 14817 | 16:23:54 INFO - PROCESS | 14817 | [#2] jquery.html Cycles:180 Average:30569.13 Median:17976.20 stddev:82869.34 (461.0%) stddev-sans-first:83070.76 16:23:54 INFO - PROCESS | 14817 | Values: 357.7 376.5 377.7 390.7 401.1 15413.9 17383.1 18706.7 19458.9 19806.3 16588.1 17073.6 17195.2 17202.6 17304.0 5418.2 5552.8 5563.1 5585.9 5604.6 5518.5 6911.6 7287.2 7293.1 7302.6 7250.2 7252.2 7263.4 7291.4 7375.9 6986.4 7050.6 7088.3 7391.7 7407.7 6888.7 7044.6 7072.4 7074.4 7077.5 24556.6 25125.2 25703.0 25920.9 26477.2 2277.7 2315.3 2369.8 2387.1 2572.4 15116.6 15234.6 15583.0 15689.3 16050.8 1933.1 2081.8 2110.5 2226.6 2337.4 94735.0 97174.1 99099.0 100157.5 101009.0 17592.0 17606.2 17754.0 17939.8 18226.5 32237.2 32424.7 32512.7 32635.8 32657.8 9747.0 9865.9 9929.7 9997.9 10138.8 8183.9 8205.7 8324.7 8370.5 8496.6 8730.3 8879.3 8912.6 8960.9 9077.3 3151.3 3294.6 3338.8 3510.8 3569.3 740.5 859.4 959.4 964.4 992.3 19155.8 19503.8 19516.1 19704.6 19809.6 955.0 963.3 986.5 1026.4 1081.6 10270.6 10389.6 10677.0 10748.2 10753.6 492736.0 497691.0 499949.5 512705.0 513690.0 56370.4 60148.4 60750.4 98303.1 100788.5 973.5 1002.2 1016.9 1020.5 1022.9 17400.3 17559.4 17654.9 17798.3 17809.4 41066.0 42191.0 42278.8 42470.8 42858.4 56971.4 59054.0 59220.0 59371.1 60014.7 5674.7 6576.7 7963.2 8177.6 8352.0 12631.5 12677.4 12849.4 12943.9 12982.5 11381.5 11706.8 11769.4 11855.9 11969.9 11300.0 11479.1 11694.9 11740.3 11904.8 11314.3 11962.3 12039.8 12142.2 12310.6 8275.1 8286.3 8286.6 8414.2 8421.4 11673.3 11794.1 11924.2 11943.8 11974.9 16:23:54 INFO - PROCESS | 14817 | 16:23:54 INFO - PROCESS | 14817 | [#3] mootools.html Cycles:180 Average:5917.84 Median:4555.68 stddev:3499.98 (76.8%) stddev-sans-first:3492.59 16:23:54 INFO - PROCESS | 14817 | Values: 1299.3 1539.6 1548.4 1717.7 1861.1 8591.2 8928.0 8934.5 9115.7 9137.4 8936.5 9104.5 9112.8 9130.3 9151.3 2018.8 2201.8 2211.8 2231.5 2297.1 2300.4 2308.5 2309.6 2313.0 2313.7 2288.5 2316.3 2318.8 2331.2 2336.6 2138.1 2138.8 2140.7 2146.8 2151.2 2282.2 2305.0 2312.3 2322.1 2329.9 8989.3 9022.3 9074.5 9075.4 9097.4 4886.6 5006.6 5015.0 5015.6 5016.1 3142.2 3259.4 3277.4 3292.1 3343.1 1052.0 1058.9 1068.4 1077.7 1093.1 13024.6 13030.6 13051.7 13074.9 13084.3 4362.7 4523.4 4602.4 4614.0 4628.1 10752.2 10771.7 10780.5 10793.9 10794.0 4705.6 5032.8 5043.1 5051.0 5052.1 4356.2 4539.7 4571.7 4574.3 4591.1 3965.1 3979.4 3986.2 3989.8 4008.5 1062.8 1164.9 1192.9 1217.6 1241.5 842.4 885.4 995.3 995.3 1014.3 5926.9 5992.4 6030.5 6117.4 6183.2 912.9 947.5 961.7 968.0 1003.8 4045.0 4099.0 4141.3 4156.5 4167.5 11273.9 11531.9 11655.0 11661.2 11675.6 11298.8 11327.3 11385.3 11403.1 11408.7 8066.9 8375.3 8377.1 8611.7 8634.5 6265.9 6283.3 6375.9 6419.1 6468.9 10370.7 10553.8 10800.9 10827.3 10830.0 10678.2 10815.5 10829.3 10953.0 11056.4 7833.6 7905.8 7909.5 8117.4 8152.1 8413.7 8465.7 8481.9 8485.7 8539.2 7580.2 7735.3 7754.0 7841.6 8005.8 7518.0 7617.7 7689.1 7745.2 7763.0 6761.9 6771.6 6829.6 6831.8 7001.4 5245.2 5301.9 5310.4 5311.3 5352.4 5280.7 5328.6 5345.7 5353.1 5477.9 16:23:54 INFO - PROCESS | 14817 | 16:23:54 INFO - PROCESS | 14817 | [#4] prototype.html Cycles:180 Average:4575.23 Median:5250.20 stddev:1357.18 (25.9%) stddev-sans-first:1322.56 16:23:54 INFO - PROCESS | 14817 | Values: 302.8 308.6 317.1 317.4 318.3 5008.0 5100.7 5143.2 5216.8 5250.9 5129.5 5141.7 5154.0 5169.8 5177.7 4021.6 4060.8 4061.9 4077.2 4090.9 3828.9 3842.5 4058.6 4064.5 4093.2 4027.6 4044.1 4059.4 4078.4 4080.4 4026.0 4038.5 4039.2 4041.7 4042.3 3495.8 3578.9 3602.4 3605.4 3625.5 4946.8 4947.4 4957.2 4968.4 4975.3 4576.3 4578.7 4586.7 4586.8 4616.4 5235.8 5331.7 5377.9 5378.6 5395.4 3855.8 3893.1 3896.9 3902.5 3908.9 5544.1 5571.4 5596.2 5598.5 5638.8 3872.9 3968.8 3993.3 4009.3 4020.6 4029.6 4033.1 4033.3 4043.5 4047.5 4587.9 4595.5 4600.0 4606.2 4617.7 5352.8 5433.4 5441.9 5459.3 5478.7 1376.4 1378.6 1380.8 1384.3 1392.4 819.1 847.8 851.4 859.4 866.9 4757.3 4793.6 5093.8 5113.2 5255.5 5545.6 5549.4 5604.6 5610.3 5624.6 5268.8 5304.5 5311.3 5329.3 5335.5 2807.4 2987.9 2987.9 2993.8 2997.4 5777.4 5790.0 5792.8 5799.2 5818.5 5372.6 5656.1 5672.5 5682.3 5754.0 5722.6 5743.4 5754.0 5782.6 5790.8 5557.7 5589.7 5618.5 5635.3 5640.3 5492.6 5564.7 5576.6 5583.9 5597.7 5756.9 5788.2 5790.4 5796.3 5805.6 3908.9 3917.6 3922.7 3952.2 3957.5 5382.4 5636.6 5643.2 5646.3 5685.3 5494.6 5495.9 5531.6 5531.9 5553.2 5249.5 5498.6 5547.8 5558.6 5575.7 5385.3 5402.4 5411.0 5425.8 5431.3 5241.5 5406.5 5474.8 5517.4 5540.5 5322.2 5346.1 5356.5 5362.2 5362.4 16:23:54 INFO - PROCESS | 14817 | 16:23:54 INFO - PROCESS | 14817 | [#5] yui.html Cycles:180 Average:15501.62 Median:4399.87 stddev:19309.11 (438.9%) stddev-sans-first:19331.05 16:23:54 INFO - PROCESS | 14817 | Values: 644.9 650.4 661.9 720.8 743.6 13506.7 13587.3 13598.0 13998.8 14550.0 13585.2 13746.7 13771.0 13777.4 13788.2 683.3 787.6 803.7 812.0 822.3 812.9 813.8 816.1 816.3 816.8 849.3 853.4 853.5 853.9 854.1 704.4 818.6 842.6 842.7 844.8 613.1 613.2 613.6 614.0 614.6 11680.2 11698.1 11719.2 11725.4 11730.6 12885.9 13142.8 13241.3 13271.7 13473.7 10552.9 10582.1 10588.9 10722.7 10859.5 4281.9 4311.6 4403.8 4520.7 4533.9 59509.7 60115.4 60141.0 60889.7 61187.0 10317.6 10474.4 10478.8 10495.3 10716.4 17477.3 17648.5 17689.0 17730.9 17794.6 7564.1 7733.5 7872.8 8004.3 8126.1 4297.8 4368.7 4377.6 4395.9 4597.8 3249.2 3309.8 3329.1 3331.1 3337.7 612.2 614.2 618.4 621.7 643.3 585.4 587.8 611.3 707.6 721.3 8411.4 8447.1 8714.8 8767.2 8805.3 606.1 614.3 618.1 648.5 664.3 4058.3 4086.3 4165.4 4233.8 4294.6 67980.0 69520.7 70428.0 71382.0 71414.6 59278.4 62234.4 62479.4 62866.4 63935.0 54030.5 54537.2 54544.7 55190.7 55503.0 7977.2 8031.8 8129.8 8232.8 8255.0 37968.0 38097.6 38202.2 38543.6 38548.4 46534.5 50382.5 52257.5 52825.5 53629.5 11153.6 11277.6 11504.4 11793.0 11831.2 12224.1 12600.3 12783.9 13098.0 13128.8 12190.0 12358.9 12445.1 12609.5 12680.7 11773.5 12479.0 12753.7 12806.7 12967.3 11766.5 12152.3 12231.9 12400.4 12402.3 10266.8 10328.5 10564.5 10669.6 10758.7 10201.4 10509.5 10642.3 10666.7 10682.1 16:23:54 INFO - PROCESS | 14817 | -------- Summary: end -------- 16:23:54 INFO - PROCESS | 14817 | 16:23:55 INFO - TEST-INFO | 14817: exit 0 16:23:55 INFO - TEST-OK | dromaeo_css | took 1116442ms 16:23:55 INFO - TEST-START | kraken 16:23:55 INFO - operating with platform_type : linux_ 16:23:55 INFO - Initialising browser for kraken test... 16:23:55 INFO - TEST-INFO | started process 15000 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA7saxW/profile http://localhost:36490/getInfo.html) 16:24:01 INFO - PROCESS | 15000 | __metrics Screen width/height:1600/1200 16:24:01 INFO - PROCESS | 15000 | colorDepth:24 16:24:01 INFO - PROCESS | 15000 | Browser inner width/height: 1024/697 16:24:01 INFO - PROCESS | 15000 | __metrics 16:24:02 ERROR - PROCESS | 15000 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:24:03 INFO - TEST-INFO | 15000: exit 0 16:24:03 INFO - Browser initialized. 16:24:03 INFO - Running cycle 1/1 for kraken test... 16:24:03 INFO - TEST-INFO | started process 15082 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA7saxW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:24:15 INFO - PROCESS | 15082 | RSS: Main: 155807744 16:24:15 INFO - PROCESS | 15082 | 16:24:18 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36490/tests/kraken/driver.html?testName=audio-beat-detection) 16:24:18 INFO - PROCESS | 15082 | RSS: Main: 213311488 16:24:18 INFO - PROCESS | 15082 | 16:24:21 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36490/tests/kraken/driver.html?testName=audio-dft) 16:24:22 INFO - PROCESS | 15082 | RSS: Main: 201990144 16:24:22 INFO - PROCESS | 15082 | 16:24:25 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36490/tests/kraken/driver.html?testName=audio-fft) 16:24:25 INFO - PROCESS | 15082 | RSS: Main: 267972608 16:24:25 INFO - PROCESS | 15082 | 16:24:27 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36490/tests/kraken/driver.html?testName=audio-oscillator) 16:24:28 INFO - PROCESS | 15082 | RSS: Main: 181579776 16:24:28 INFO - PROCESS | 15082 | 16:24:30 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36490/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:24:31 INFO - PROCESS | 15082 | RSS: Main: 183025664 16:24:31 INFO - PROCESS | 15082 | 16:24:34 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36490/tests/kraken/driver.html?testName=imaging-darkroom) 16:24:34 INFO - PROCESS | 15082 | RSS: Main: 217460736 16:24:34 INFO - PROCESS | 15082 | 16:24:38 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36490/tests/kraken/driver.html?testName=imaging-desaturate) 16:24:38 INFO - PROCESS | 15082 | RSS: Main: 219693056 16:24:38 INFO - PROCESS | 15082 | 16:24:41 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36490/tests/kraken/driver.html?testName=json-parse-financial) 16:24:42 INFO - PROCESS | 15082 | RSS: Main: 219435008 16:24:42 INFO - PROCESS | 15082 | 16:24:43 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36490/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:24:44 INFO - PROCESS | 15082 | RSS: Main: 207339520 16:24:44 INFO - PROCESS | 15082 | 16:24:46 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36490/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:24:46 INFO - PROCESS | 15082 | RSS: Main: 208494592 16:24:46 INFO - PROCESS | 15082 | 16:24:48 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36490/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:24:49 INFO - PROCESS | 15082 | RSS: Main: 216649728 16:24:49 INFO - PROCESS | 15082 | 16:24:52 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:24:52 INFO - PROCESS | 15082 | RSS: Main: 221212672 16:24:52 INFO - PROCESS | 15082 | 16:24:55 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:24:55 INFO - PROCESS | 15082 | RSS: Main: 219811840 16:24:55 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | Cycle 1(1): loaded http://localhost:36490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36490/tests/kraken/driver.html?testName=ai-astar) 16:24:57 INFO - PROCESS | 15082 | RSS: Main: 261484544 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | __start_tp_report 16:24:57 INFO - PROCESS | 15082 | _x_x_mozilla_page_load 16:24:57 INFO - PROCESS | 15082 | _x_x_mozilla_page_load_details 16:24:57 INFO - PROCESS | 15082 | |i|pagename|runs| 16:24:57 INFO - PROCESS | 15082 | |0;ai-astar;109;103;109;109;109;110;109;108;109;109 16:24:57 INFO - PROCESS | 15082 | |1;audio-beat-detection;147;124;125;188;125;127;148;124;159;124 16:24:57 INFO - PROCESS | 15082 | |2;audio-dft;159;161;161;160;159;159;160;160;161;161 16:24:57 INFO - PROCESS | 15082 | |3;audio-fft;74;73;75;126;74;74;74;76;100;75 16:24:57 INFO - PROCESS | 15082 | |4;audio-oscillator;82;83;110;83;82;82;124;83;83;126 16:24:57 INFO - PROCESS | 15082 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;116;116;116 16:24:57 INFO - PROCESS | 15082 | |6;imaging-darkroom;145;146;145;145;145;146;146;146;147;145 16:24:57 INFO - PROCESS | 15082 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;94 16:24:57 INFO - PROCESS | 15082 | |8;json-parse-financial;54;55;54;56;54;54;54;54;52;53 16:24:57 INFO - PROCESS | 15082 | |9;json-stringify-tinderbox;62;63;62;62;64;62;63;62;62;62 16:24:57 INFO - PROCESS | 15082 | |10;stanford-crypto-aes;78;79;77;84;78;79;82;80;142;79 16:24:57 INFO - PROCESS | 15082 | |11;stanford-crypto-ccm;128;132;119;122;126;120;138;119;129;122 16:24:57 INFO - PROCESS | 15082 | |12;stanford-crypto-pbkdf2;156;154;158;162;152;157;155;159;154;154 16:24:57 INFO - PROCESS | 15082 | |13;stanford-crypto-sha256-iterative;65;63;62;62;61;61;62;61;60;61 16:24:57 INFO - PROCESS | 15082 | __end_tp_report 16:24:57 INFO - PROCESS | 15082 | __start_cc_report 16:24:57 INFO - PROCESS | 15082 | _x_x_mozilla_cycle_collect,1637 16:24:57 INFO - PROCESS | 15082 | __end_cc_report 16:24:57 INFO - PROCESS | 15082 | __startTimestamp1464564297877__endTimestamp 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | ------- Summary: start ------- 16:24:57 INFO - PROCESS | 15082 | Number of tests: 14 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#0] ai-astar Cycles:10 Average:108.40 Median:109.00 stddev:1.96 (1.8%) stddev-sans-first:2.06 16:24:57 INFO - PROCESS | 15082 | Values: 109.0 103.0 109.0 109.0 109.0 110.0 109.0 108.0 109.0 109.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:137.00 stddev:21.49 (15.7%) stddev-sans-first:22.60 16:24:57 INFO - PROCESS | 15082 | Values: 147.0 124.0 125.0 188.0 125.0 127.0 148.0 124.0 159.0 124.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.50 stddev:0.88 (0.5%) stddev-sans-first:0.83 16:24:57 INFO - PROCESS | 15082 | Values: 159.0 161.0 161.0 160.0 159.0 159.0 160.0 160.0 161.0 161.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#3] audio-fft Cycles:10 Average:82.10 Median:74.00 stddev:17.42 (23.5%) stddev-sans-first:18.23 16:24:57 INFO - PROCESS | 15082 | Values: 74.0 73.0 75.0 126.0 74.0 74.0 74.0 76.0 100.0 75.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:82.50 stddev:18.55 (22.5%) stddev-sans-first:19.17 16:24:57 INFO - PROCESS | 15082 | Values: 82.0 83.0 110.0 83.0 82.0 82.0 124.0 83.0 83.0 126.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:24:57 INFO - PROCESS | 15082 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 16:24:57 INFO - PROCESS | 15082 | Values: 145.0 146.0 145.0 145.0 145.0 146.0 146.0 146.0 147.0 145.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:24:57 INFO - PROCESS | 15082 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.05 (2.0%) stddev-sans-first:1.12 16:24:57 INFO - PROCESS | 15082 | Values: 54.0 55.0 54.0 56.0 54.0 54.0 54.0 54.0 52.0 53.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#9] json-stringify-tinderbox Cycles:10 Average:62.40 Median:62.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 16:24:57 INFO - PROCESS | 15082 | Values: 62.0 63.0 62.0 62.0 64.0 62.0 63.0 62.0 62.0 62.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:79.00 stddev:19.85 (25.1%) stddev-sans-first:20.86 16:24:57 INFO - PROCESS | 15082 | Values: 78.0 79.0 77.0 84.0 78.0 79.0 82.0 80.0 142.0 79.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#11] stanford-crypto-ccm Cycles:10 Average:125.50 Median:127.00 stddev:6.29 (5.0%) stddev-sans-first:6.61 16:24:57 INFO - PROCESS | 15082 | Values: 128.0 132.0 119.0 122.0 126.0 120.0 138.0 119.0 129.0 122.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.10 Median:156.50 stddev:2.96 (1.9%) stddev-sans-first:3.14 16:24:57 INFO - PROCESS | 15082 | Values: 156.0 154.0 158.0 162.0 152.0 157.0 155.0 159.0 154.0 154.0 16:24:57 INFO - PROCESS | 15082 | 16:24:57 INFO - PROCESS | 15082 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.40 (2.3%) stddev-sans-first:0.88 16:24:57 INFO - PROCESS | 15082 | Values: 65.0 63.0 62.0 62.0 61.0 61.0 62.0 61.0 60.0 61.0 16:24:57 INFO - PROCESS | 15082 | -------- Summary: end -------- 16:24:57 INFO - PROCESS | 15082 | 16:24:58 INFO - TEST-INFO | 15082: exit 0 16:24:58 INFO - TEST-OK | kraken | took 63487ms 16:24:58 INFO - SUITE-END | took 1179s 16:24:59 INFO - Completed test suite (00:19:40) 16:24:59 INFO - javascript benchmark 16:24:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3105.9378812423765, 3121.3913286909055, 3137.074563003914, 3144.072498612909, 3173.9523907141397, 5202.687838729669, 5271.288376069229, 5345.33183352081, 5356.196570514425, 5361.356637203541, 5311.521963023327, 5331.306930099082, 5334.386545547263, 5385.919211211825, 5427.213054107152, 4745.145873742734, 4831.444383895861, 4843.564079232869, 4870.561649451549, 4963.280324352967, 4861.465238823745, 4883.365162528867, 4901.0, 4917.09033828741, 4918.409790825106, 4674.532546745315, 4774.212254977924, 4842.515748425165, 4843.515648435164, 4846.200376937801, 4826.276058591205, 4850.151223535867, 4857.927131093001, 4901.191303434929, 4954.87612809679, 4857.465678775332, 4875.049365373754, 4880.902381952341, 4882.43851957026, 4888.633352498541, 5134.460881607452, 5244.685318880879, 5319.468053194688, 5345.973270133624, 5404.378496472917, 5874.3244526879525, 5902.291724992985, 5927.199828023205, 5930.288365396137, 5941.643501389931, 5580.079286917654, 5724.227229324071, 5760.222369980041, 5808.273965754281, 5808.941910580925, 5411.080116380185, 5416.4312747161375, 5435.0, 5435.103207970719, 5435.402105768362, 5924.170616113747, 5937.52499800015, 5939.3763654816485, 5942.0, 5944.583879128506, 5510.779568817283, 5600.0, 5667.943320566824, 5700.087985922315, 5710.628809127394, 5797.24635797352, 5811.331696854916, 5840.328362238312, 5845.064789633639, 5846.912296315559, 5758.654480731169, 5770.134479827975, 5773.480386765212, 5773.538116950634, 5792.739326730307, 5505.339359276913, 5689.431056894277, 5795.072788353843, 5810.244668193192, 5810.825675229666, 4367.344898265285, 4370.672199585044, 4394.450693663292, 4407.537208593116, 4434.290513517822, 3884.028992751812, 3903.316919539126, 3922.3135951208424, 3929.6070392960473, 3956.4263181838473, 5366.517013468807, 5389.056915039931, 5430.8913821723345, 5508.641938274, 5536.833894983157, 5753.079507278815, 5791.218185544982, 5818.0981947798155, 5824.767009319579, 5871.500922421556, 5494.697791621499, 5503.064479038493, 5508.283923089973, 5510.393856675762, 5510.586705997066, 4732.763361831963, 4805.687630304019, 4824.782884770194, 4858.1012512685265, 4913.754312284372, 5970.761169553256, 5971.253593300838, 5975.223220981245, 5985.46130848217, 6023.789167379121, 5830.95042892275, 5840.503557197686, 5840.853978650398, 5843.8539036522725, 5846.064629659234, 5774.1916131740745, 5828.271466066742, 5832.387599302182, 5833.533317334688, 5974.790882319098, 5613.607047506635, 5614.494695477427, 5616.21373007771, 5619.241402410622, 5621.9156712650165, 5654.830355089354, 5706.857328566819, 5707.942920570741, 5717.42825717425, 5718.885622287614, 5535.474129957647, 5553.4724201200825, 5607.971960140173, 5637.46444087811, 5670.121131224667, 5632.774689012557, 5635.323761148688, 5636.887262254814, 5641.26663533738, 5645.548356131581, 5372.5970552207955, 5535.640183388067, 5538.695371754592, 5547.306586676666, 5547.918155959549, 5579.665220086646, 5622.24099746529, 5646.265985421869, 5769.59612827098, 5777.884442311046, 5510.201020851874, 5516.806911758069, 5517.613767036269, 5562.749676264497, 5583.050881350261], "value": 5298.233749269609, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.4509321983094, 643.3727116061839, 649.6102338596843, 652.3378770547897, 657.8454063295122, 31051.68948310516, 33671.66328336716, 43054.99999999996, 44069.55930440695, 44178.116437671306, 45051.0, 45586.7720661397, 45588.0, 45647.543524564746, 45706.31440528395, 8375.874361884562, 8521.190486903764, 8527.891374121356, 8536.231739143475, 8537.957310213471, 8618.224359807617, 8757.25563327118, 8765.517896515688, 8775.385722999392, 8776.07851175627, 8755.518446485441, 8815.955920220389, 8817.691380801672, 8818.20636142747, 8820.514871682055, 8753.474791512497, 8782.868256976151, 8801.999999999967, 8802.735917922473, 8805.911940880573, 8657.783555411102, 8750.781230469289, 8765.123487651184, 8768.780780480476, 8770.0, 35396.11509712252, 35463.64536354629, 35567.11082222939, 35720.64279357199, 35773.46339804905, 3268.6894744999186, 3457.7060949819297, 3459.757816952814, 3480.2343484433386, 3587.3004764071015, 31721.524177137362, 31835.0, 32131.977176879187, 32355.35289294201, 32624.0, 4213.494380674308, 4224.387463817729, 4246.57534246576, 4254.638355739766, 4328.026194106301, 337819.0, 338536.0, 339159.0, 339622.0, 340445.0, 57421.71289143527, 57695.0, 58220.417795822344, 58282.0, 58336.41663583395, 60731.0, 60968.78062438726, 61085.08372374418, 61194.694026529585, 61300.38699613036, 14202.715945681028, 14364.138151710931, 14398.856011439751, 14404.855951440351, 14409.567712968417, 11924.046076313874, 11940.283582985048, 11956.043516518832, 11988.820167697491, 11989.820152697717, 15974.0, 16115.19424028794, 16153.353865845469, 16241.51275461738, 16379.672406552041, 5704.71476426177, 5773.653580785082, 5775.6823374714795, 5862.530997520188, 5866.078027927002, 1004.2719028704131, 1011.0950699124243, 1046.1473898772476, 1084.6312253833576, 1137.7895089408487, 31877.20306992344, 32155.35689286248, 32170.839145804122, 32238.838805805823, 32459.51310730341, 1071.8285074388216, 1084.5770149641646, 1108.534415545473, 1117.63118171005, 1191.5055252070467, 16206.189690515426, 16668.74996875048, 16684.0, 16795.412160574564, 16983.575410614834, 137051.0, 137816.31091844678, 137878.0, 138358.0, 138825.0, 58539.0, 58653.12020319699, 58829.0, 58909.705451472466, 58913.70543147257, 1239.727260002816, 1326.1247576599399, 1332.9600111996656, 1356.7286542691502, 1386.750384930702, 27939.720602794525, 29119.708802912548, 29140.271493211992, 29643.40713185681, 29701.702982969895, 49640.25539616981, 49647.751761240965, 50111.74944125256, 50147.74926125492, 50227.74886125546, 385732.0, 387349.0, 388113.0, 388487.0, 388750.0, 40629.796851015555, 40816.79591602023, 41013.58986410217, 41061.38407923943, 42266.1546769069, 47793.76103119462, 48421.5157848417, 48654.513454866414, 49324.506754931994, 49623.50376496189, 39976.40035399413, 41178.1764364717, 41193.176136477705, 41782.164356712085, 42633.78683106565, 39964.2007159861, 40331.79834100811, 40379.59620403758, 41205.793971029954, 41227.0, 40832.79583602182, 41356.79321603373, 41783.58216417797, 41828.53600123981, 41875.162496750505, 33538.66461335453, 35966.0, 36039.45940810838, 36963.44554831626, 36983.63016369802, 27809.0, 27912.30219244535, 28022.299442513286, 28068.85965570159, 28073.859630701714], "value": 18968.903074461115, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.69059763304716, 376.53497930056375, 377.73369774309117, 390.73625302920533, 401.1255463090462, 15413.922930385346, 17383.13084345784, 18706.719399209007, 19458.90270548649, 19806.30677926273, 16588.08765517896, 17073.57316067099, 17195.226214820334, 17202.569935751613, 17304.0, 5418.187271909215, 5552.805651802188, 5563.054280772259, 5585.944140558603, 5604.607677462579, 5518.475744804237, 6911.619860907648, 7287.198408175096, 7293.124825021005, 7302.598357090358, 7250.238724933885, 7252.166000909884, 7263.418926485869, 7291.380232680229, 7375.852482950338, 6986.371226589631, 7050.647467626598, 7088.326608972191, 7391.667374968112, 7407.703691852319, 6888.724451021953, 7044.6125463100025, 7072.434205263567, 7074.3633073023675, 7077.5399599025895, 24556.64521146919, 25125.24624261275, 25703.0, 25920.8703956479, 26477.205683829518, 2277.6697378880135, 2315.3401280635107, 2369.8104151667826, 2387.1048356866177, 2572.3826281692445, 15116.622084447867, 15234.619134521614, 15583.0, 15689.293981770848, 16050.75923861143, 1933.1204302042677, 2081.8438617103625, 2110.525131845338, 2226.5546890621777, 2337.440502649596, 94735.0, 97174.05651886994, 99099.0, 100157.4992125042, 101009.0, 17592.0, 17606.23937606255, 17754.0, 17939.820601794076, 18226.544336391697, 32237.194070148435, 32424.675753242638, 32512.67487325144, 32635.836820816217, 32657.754491915897, 9747.0, 9865.852012219822, 9929.65246216379, 9997.900020999843, 10138.847917281246, 8183.918160818315, 8205.71280005197, 8324.66701331952, 8370.455920365157, 8496.575171241413, 8730.257928076184, 8879.289656827568, 8912.55437228136, 8960.938984033579, 9077.319201059947, 3151.2909595340866, 3294.637589865113, 3338.7996720196866, 3510.8238740021866, 3569.3016238535897, 740.5149626994328, 859.4113032572708, 959.3524371049514, 964.3683387381277, 992.2508506077864, 19155.80844191568, 19503.80496195048, 19516.12177452018, 19704.635443746844, 19809.603807923762, 954.9570269337896, 963.2967933408651, 986.487026746088, 1026.3892983674702, 1081.605214096849, 10270.640527581649, 10389.584416623251, 10677.0, 10748.247622666338, 10753.623623173151, 492736.0, 497691.0, 499949.5012624641, 512705.0, 513690.0, 56370.43629563652, 60148.39851601516, 60750.39249607448, 98303.10181388997, 100788.49605752071, 973.454865275448, 1002.1581871228203, 1016.867807185076, 1020.5152552537485, 1022.9335093219066, 17400.303987840853, 17559.368264918532, 17654.911725441292, 17798.288068477636, 17809.376671816422, 41066.0, 42191.0, 42278.788606056776, 42470.78764606157, 42858.357124642534, 56971.43028569661, 59054.0, 59220.0, 59371.10943335767, 60014.699926500085, 5674.659520428788, 6576.671166441754, 7963.243491868263, 8177.550234737147, 8351.95824020876, 12631.494740210655, 12677.41435901033, 12849.421776020288, 12943.870561294643, 12982.480700771863, 11381.487833047699, 11706.824397634213, 11769.352685602375, 11855.940720296343, 11969.94015029954, 11300.0, 11479.139064570358, 11694.941525292319, 11740.295582265091, 11904.761904761683, 11314.264572802742, 11962.342071186169, 12039.75920481568, 12142.210756300812, 12310.569130080405, 8275.055361989807, 8286.295664868554, 8286.627101780314, 8414.158584141536, 8421.410501265098, 11673.299602023906, 11794.056475482112, 11924.165308428326, 11943.761124777282, 11974.880251197725], "value": 10229.603138823039, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.2529295655002, 1539.6074001129714, 1548.401248292571, 1717.7251639737638, 1861.0601646168675, 8591.183837535433, 8928.0, 8934.463932164066, 9115.68095116671, 9137.406068605536, 8936.508492032935, 9104.499252541107, 9112.772180695485, 9130.269578433727, 9151.267898568116, 2018.8384929205702, 2201.8128459081, 2211.7898799614013, 2231.4867580456503, 2297.1041293895396, 2300.4133945843787, 2308.53829234153, 2309.584274830538, 2313.0400883633274, 2313.699219101523, 2288.5079707862806, 2316.2587971849016, 2318.8492747971413, 2331.160782118436, 2336.5794157051723, 2138.0699395762817, 2138.8395870309632, 2140.743110826695, 2146.774588668183, 2151.2470635277605, 2282.2240438251074, 2304.953900921972, 2312.2716344351475, 2322.094383190557, 2329.895154718025, 8989.280857531381, 9022.323325750529, 9074.546272686339, 9075.410098343587, 9097.408668436598, 4886.560209581155, 5006.5744411725045, 5014.974925125351, 5015.6238282128625, 5016.07202667504, 3142.1516190628404, 3259.429599820022, 3277.4100661880852, 3292.111129994912, 3343.130785995634, 1051.9526621301984, 1058.9099926506256, 1068.4444089073638, 1077.7413420778958, 1093.0981939899534, 13024.609261722164, 13030.609081727564, 13051.673708157277, 13074.934625326907, 13084.280364579945, 4362.716423432466, 4523.389342438729, 4602.3786788783755, 4614.0, 4628.120657075145, 10752.247342685936, 10771.676849694522, 10780.514876830404, 10793.892061079447, 10794.028537431668, 4705.576498115118, 5032.7735251913755, 5043.142665746832, 5051.0, 5052.115879721107, 4356.237658409766, 4539.7049191802425, 4571.657125715585, 4574.290984897346, 4591.127685739699, 3965.1276719121747, 3979.403089536535, 3986.2390197234363, 3989.84040638377, 4008.4567441534637, 1062.824633935392, 1164.8776878427811, 1192.9164958452825, 1217.6164508179895, 1241.472374240944, 842.3598065470164, 885.4156256870434, 995.2679893691718, 995.3430735714394, 1014.3457469931852, 5926.940730592639, 5992.40075992406, 6030.48740857032, 6117.449429551361, 6183.2400337576055, 912.9406588571721, 947.5167664491021, 961.6586111930304, 967.9758006049905, 1003.8054714889258, 4044.9595504045174, 4098.979505102515, 4141.295979683401, 4156.501219853637, 4167.541570427251, 11273.943630281796, 11531.942340288246, 11655.0, 11661.18371713989, 11675.649730508097, 11298.830517542243, 11327.320360778214, 11385.316880987168, 11403.087752979907, 11408.657740267805, 8066.878996815052, 8375.329973602098, 8377.120402357787, 8611.698590549426, 8634.481931084156, 6265.906011409924, 6283.3088360281245, 6375.904361434675, 6419.069234960998, 6468.8706225874275, 10370.740731481472, 10553.788924221626, 10800.945995269973, 10827.296225745302, 10830.0, 10678.199135064746, 10815.459227038775, 10829.29609575375, 10953.0, 11056.391898445663, 7833.647485863035, 7905.802354940943, 7909.485883417559, 8117.391195660467, 8152.144024877302, 8413.70552030676, 8465.703700370457, 8481.872771908304, 8485.703000394957, 8539.188777066169, 7580.241975802375, 7735.342495887912, 7754.030746156731, 7841.647125879464, 8005.799855003672, 7517.962410187914, 7617.69529218825, 7689.077310722749, 7745.225477452435, 7763.0, 6761.898571521529, 6771.62756048422, 6829.624370659462, 6831.760888368884, 7001.404880585207, 5245.160774276097, 5301.867453313698, 5310.415854256106, 5311.282976798083, 5352.437994010573, 5280.735963201748, 5328.600354973315, 5345.705986170798, 5353.063213937623, 5477.945220547744], "value": 4661.1986917297145, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.8228486335495, 308.57570840094866, 317.1358049315614, 317.4222914295981, 318.2536950850255, 5008.0, 5100.668456550322, 5143.199880041983, 5216.817411390597, 5250.921236181455, 5129.461406552315, 5141.691498510092, 5154.020736060155, 5169.844904652857, 5177.705573606599, 4021.6125436724355, 4060.817263223147, 4061.878143655695, 4077.225327187825, 4090.9181816363803, 3828.8659896903614, 3842.4812650292133, 4058.614431629005, 4064.4512990746316, 4093.2427500912277, 4027.597240275978, 4044.1102957349485, 4059.3910913363084, 4078.449409329747, 4080.3879418087226, 4025.9798701006453, 4038.4749982502235, 4039.171969746195, 4041.7066538707627, 4042.292598795199, 3495.755297129202, 3578.9284214315567, 3602.3875941090078, 3605.369060414417, 3625.5105560749225, 4946.812764936425, 4947.406311242638, 4957.206846904514, 4968.403791545002, 4975.278584605212, 4576.26779715244, 4578.748168850712, 4586.7477288749105, 4586.830358258656, 4616.422947131608, 5235.842924712265, 5331.706756128411, 5377.919331210035, 5378.569714422837, 5395.406505284415, 3855.8072096395067, 3893.10458594522, 3896.883093507199, 3902.4731661225937, 3908.921821563581, 5544.140658197945, 5571.359293681197, 5596.244506991545, 5598.468145526169, 5638.802641907555, 3872.9225415491533, 3968.8015599219925, 3993.3011722948368, 4009.278329900646, 4020.647920966209, 4029.6171863672903, 4033.092554175287, 4033.3143365627916, 4043.4945631796027, 4047.5142982841658, 4587.8623641290815, 4595.4954549770455, 4599.954000459952, 4606.170889239902, 4617.746023968714, 5352.839414817562, 5433.402325744165, 5441.891162176735, 5459.2629994950175, 5478.726063696799, 1376.4356613788298, 1378.5519706095558, 1380.82049333588, 1384.3286006286942, 1392.4430227908715, 819.0703551469126, 847.7541512961295, 851.3784936996028, 859.4113032572708, 866.9089745576749, 4757.26262429324, 4793.616510679138, 5093.796248150106, 5113.233015047698, 5255.474452554714, 5545.584081193927, 5549.361823390362, 5604.570834437192, 5610.298712660918, 5624.634398764068, 5268.789248430096, 5304.549113325334, 5311.256424100629, 5329.307190065267, 5335.466453354711, 2807.368342123006, 2987.850607469618, 2987.893535057779, 2993.75759059992, 2997.370552184022, 5777.393373695785, 5790.0, 5792.797252096157, 5799.18811366409, 5818.516278348994, 5372.5970552207955, 5656.095024796013, 5672.489475947102, 5682.289713785777, 5753.971230143822, 5722.62656962326, 5743.396943320975, 5753.971230143906, 5782.595218334676, 5790.812883358902, 5557.749901254534, 5589.664620122806, 5618.52242559387, 5635.267415235993, 5640.32316122068, 5492.615516913777, 5564.721763911869, 5576.553875690016, 5583.916241256303, 5597.748101335368, 5756.94243057564, 5788.21859049023, 5790.449907258803, 5796.333421656564, 5805.593608447537, 3908.863189788344, 3917.6082391760597, 3922.666573341298, 3952.2095580883365, 3957.4657421248917, 5382.407935127055, 5636.605437619492, 5643.1535269709875, 5646.265985422033, 5685.289338832646, 5494.587905906993, 5495.917561236664, 5531.585131115263, 5531.8893622126525, 5553.194786755978, 5249.480249480194, 5498.560115190855, 5547.778088876561, 5558.638688485235, 5575.749091290821, 5385.299911011543, 5402.378726446509, 5410.997930765642, 5425.83722488326, 5431.34823821144, 5241.47585241488, 5406.540444062299, 5474.781008759605, 5517.420670829505, 5540.529055030368, 5322.228276899906, 5346.091164501966, 5356.491133342326, 5362.222477740784, 5362.410134885237], "value": 4133.015306081289, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.8581312111337, 650.3886346833979, 661.9172603424572, 720.7693538067817, 743.6207534157579, 13506.662333441669, 13587.320633968311, 13598.0, 13998.79001814971, 14550.0, 13585.184888906673, 13746.725065498684, 13771.036027478081, 13777.380017899193, 13788.241646709426, 683.3200965039792, 787.5668382389691, 803.6785285885634, 812.0012384766741, 822.2928281677753, 812.9471584347028, 813.8128230506987, 816.143049797713, 816.3428440105713, 816.7998840284139, 849.3035710717214, 853.4495250563383, 853.4751128056232, 853.8548446764065, 854.1245223645751, 704.3625518905399, 818.615250832111, 842.6039761312173, 842.6629348260683, 844.7845799321165, 613.1171113596407, 613.2487702564367, 613.6164896939413, 614.0053113955377, 614.6097228260043, 11680.240784348991, 11698.064154867676, 11719.179657423983, 11725.4137293135, 11730.648080557597, 12885.87114128856, 13142.802857957138, 13241.271730054843, 13271.734565308736, 13473.730525389536, 10552.894471055344, 10582.100521455686, 10588.891878693694, 10722.678319650424, 10859.51132199045, 4281.871543853689, 4311.611954924072, 4403.823847046115, 4520.728756274634, 4533.909321813579, 59509.7024514879, 60115.39884601142, 60141.0, 60889.6955515224, 61187.0, 10317.587296508205, 10474.371537707762, 10478.842817357745, 10495.26533142688, 10716.35701857891, 17477.300907963792, 17648.47054588339, 17689.0, 17730.91134544319, 17794.644107117787, 7564.130125035691, 7733.49732267412, 7872.803179920548, 8004.279614834576, 8126.065502467174, 4297.828086876552, 4368.650507959355, 4377.584129507692, 4395.912081758411, 4597.816087356535, 3249.2201871550656, 3309.834508274577, 3329.1011426914597, 3331.0506505645776, 3337.732981361485, 612.1643956000116, 614.241411856357, 618.3816183816184, 621.7481919822479, 643.3373625166087, 585.4379795396381, 587.8177764892897, 611.2817439508559, 707.6320313437087, 721.2607077745248, 8411.369147313975, 8447.113053129457, 8714.782130446789, 8767.210951014311, 8805.295576353876, 606.099812397675, 614.2720875762236, 618.1407843098098, 648.45205801098, 664.3489380407132, 4058.3100872851687, 4086.2849001424634, 4165.437665915063, 4233.7883105844585, 4294.613484786384, 67980.0, 69520.6523967387, 70428.0, 71382.0, 71414.64292678503, 59278.40721592729, 62234.37765622286, 62479.37520624827, 62866.37133628697, 63935.0, 54030.459695403326, 54537.181942270894, 54544.72727636416, 55190.72404637951, 55503.0, 7977.162397948364, 8031.799205019921, 8129.75610731679, 8232.794180145544, 8254.958725206336, 37968.0, 38097.61527978075, 38202.235955280186, 38543.614563854004, 38548.42177367398, 46534.53465465302, 50382.49617503925, 52257.47742522678, 52825.471745282055, 53629.46370536245, 11153.553857845594, 11277.605283815356, 11504.36725980079, 11793.0, 11831.230969986924, 12224.105263684143, 12600.30698311565, 12783.93608031954, 13098.0, 13128.803067953797, 12190.0, 12358.876411235773, 12445.09151829128, 12609.49562017509, 12680.6829829255, 11773.529058837894, 12479.0, 12753.744925101631, 12806.679833003876, 12967.286799226133, 11766.52933882635, 12152.331621760535, 12231.877681223074, 12400.441980110736, 12402.25586464815, 10266.845997309896, 10328.535215915152, 10564.471776411303, 10669.57321707154, 10758.73103172427, 10201.38791672502, 10509.527071281658, 10642.255042147284, 10666.73333166677, 10682.145428365557], "value": 6619.522236897289, "unit": "score"}], "name": "dromaeo_css", "value": 7127.624774642993, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 103.0, 109.0, 109.0, 109.0, 110.0, 109.0, 108.0, 109.0, 109.0], "value": 108.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 124.0, 125.0, 188.0, 125.0, 127.0, 148.0, 124.0, 159.0, 124.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 161.0, 160.0, 159.0, 159.0, 160.0, 160.0, 161.0, 161.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 75.0, 126.0, 74.0, 74.0, 74.0, 76.0, 100.0, 75.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 110.0, 83.0, 82.0, 82.0, 124.0, 83.0, 83.0, 126.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 147.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 56.0, 54.0, 54.0, 54.0, 54.0, 52.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 64.0, 62.0, 63.0, 62.0, 62.0, 62.0], "value": 62.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 77.0, 84.0, 78.0, 79.0, 82.0, 80.0, 142.0, 79.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 132.0, 119.0, 122.0, 126.0, 120.0, 138.0, 119.0, 129.0, 122.0], "value": 125.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 154.0, 158.0, 162.0, 152.0, 157.0, 155.0, 159.0, 154.0, 154.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 62.0, 62.0, 61.0, 61.0, 62.0, 61.0, 60.0, 61.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1486.1, "alertThreshold": 2.0}]} 16:24:59 INFO - Return code: 0 16:24:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:24:59 ERROR - # TBPL SUCCESS # 16:24:59 INFO - Running post-action listener: _resource_record_post_action 16:24:59 INFO - ##### 16:24:59 INFO - ##### Finished run-tests step (success) 16:24:59 INFO - ##### 16:24:59 INFO - Running post-run listener: _resource_record_post_run 16:24:59 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 536473600; Read time: 8; Write time: 2312472 16:24:59 INFO - TinderboxPrint: CPU usage
13.3% 16:24:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 16:24:59 INFO - TinderboxPrint: I/O write bytes / time
536,473,600 / 2,312,472 16:24:59 INFO - TinderboxPrint: CPU idle
8,285.4 (86.6%) 16:24:59 INFO - TinderboxPrint: CPU user
1,203.5 (12.6%) 16:24:59 INFO - TinderboxPrint: Swap in / out
0 / 0 16:24:59 INFO - install - Wall time: 17s; CPU: 26.0%; Read bytes: 0; Write bytes: 246968320; Read time: 0; Write time: 1475396 16:24:59 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289099776; Read time: 8; Write time: 833820 16:24:59 INFO - Running post-run listener: _upload_blobber_files 16:24:59 INFO - Blob upload gear active. 16:24:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:24:59 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 16:24:59 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'] 16:24:59 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 16:25:00 INFO - (blobuploader) - INFO - Open directory for files ... 16:25:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:25:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:25:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:25:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:25:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:25:00 INFO - (blobuploader) - INFO - Done attempting. 16:25:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:25:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:25:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:25:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:25:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:25:04 INFO - (blobuploader) - INFO - Done attempting. 16:25:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:25:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:25:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:25:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:25:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:25:05 INFO - (blobuploader) - INFO - Done attempting. 16:25:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:25:05 INFO - Return code: 0 16:25:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:25:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:25:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3dd5969485ee49f707705a4d40e980969fddafeaa4a43fee9ded671e13d04f7bc6690d30ad6bc63f77881d454cb066a273162cb4e0abd667cb31a3cf41f7a708", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/13a7db63e5239c7d0653bfd4f3d8d5395781c64d2ae96588d60029eb56708d0b3250beb53c3d68700900488f59cbd2be31658c69c165a2de61677660bcb40b62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7f6aacc008376da30198909097b7ffd51f06e5d2ac9f15e5f5ecebea1f1fba9f91084733d89d0fecd4700497e99e63f4d022ac34b895e6cb9f5bccb9bbac2312"} 16:25:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:25:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:25:05 INFO - Contents: 16:25:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3dd5969485ee49f707705a4d40e980969fddafeaa4a43fee9ded671e13d04f7bc6690d30ad6bc63f77881d454cb066a273162cb4e0abd667cb31a3cf41f7a708", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/13a7db63e5239c7d0653bfd4f3d8d5395781c64d2ae96588d60029eb56708d0b3250beb53c3d68700900488f59cbd2be31658c69c165a2de61677660bcb40b62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7f6aacc008376da30198909097b7ffd51f06e5d2ac9f15e5f5ecebea1f1fba9f91084733d89d0fecd4700497e99e63f4d022ac34b895e6cb9f5bccb9bbac2312"} 16:25:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:25:05 INFO - Copying logs to upload dir... 16:25:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:25:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.648412 ========= master_lag: 1.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-05-29 16:25:06.821395) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-29 16:25:06.827001) ========= 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=8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910 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/3dd5969485ee49f707705a4d40e980969fddafeaa4a43fee9ded671e13d04f7bc6690d30ad6bc63f77881d454cb066a273162cb4e0abd667cb31a3cf41f7a708", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/13a7db63e5239c7d0653bfd4f3d8d5395781c64d2ae96588d60029eb56708d0b3250beb53c3d68700900488f59cbd2be31658c69c165a2de61677660bcb40b62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7f6aacc008376da30198909097b7ffd51f06e5d2ac9f15e5f5ecebea1f1fba9f91084733d89d0fecd4700497e99e63f4d022ac34b895e6cb9f5bccb9bbac2312"} build_url:https://queue.taskcluster.net/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011325 build_url: 'https://queue.taskcluster.net/v1/task/Gi5ySdyOT-e_5jXw0D0Cmw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3dd5969485ee49f707705a4d40e980969fddafeaa4a43fee9ded671e13d04f7bc6690d30ad6bc63f77881d454cb066a273162cb4e0abd667cb31a3cf41f7a708", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/13a7db63e5239c7d0653bfd4f3d8d5395781c64d2ae96588d60029eb56708d0b3250beb53c3d68700900488f59cbd2be31658c69c165a2de61677660bcb40b62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7f6aacc008376da30198909097b7ffd51f06e5d2ac9f15e5f5ecebea1f1fba9f91084733d89d0fecd4700497e99e63f4d022ac34b895e6cb9f5bccb9bbac2312"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-29 16:25:06.860875) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 16:25:06.861263) ========= 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=8783db0840cc980e80bb0bd400000236-1464561775.994504-354151910 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004575 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 16:25:06.914777) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-29 16:25:06.915164) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-29 16:25:06.918535) ========= ========= Total master_lag: 1.32 =========