builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1463722772.32 results: success (0) buildid: 20160519193117 builduid: 8c66b5c99d874bcf977c2b0b9c5e6aa8 revision: f70b8561b4796217c3328dfc97b61e8ae934c1dd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:32.318618) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:32.319077) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:32.319395) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:32.347963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:32.348574) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-19 22:39:33-- 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.8M=0s 2016-05-19 22:39:33 (46.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089539 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:32.452764) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:32.453084) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015927 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:32.502241) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:32.502621) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev f70b8561b4796217c3328dfc97b61e8ae934c1dd --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 f70b8561b4796217c3328dfc97b61e8ae934c1dd --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=82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-19 22:39:33,282 truncating revision to first 12 chars 2016-05-19 22:39:33,282 Setting DEBUG logging. 2016-05-19 22:39:33,282 attempt 1/10 2016-05-19 22:39:33,282 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/f70b8561b479?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 22:39:33,552 unpacking tar archive at: mozilla-inbound-f70b8561b479/testing/mozharness/ program finished with exit code 0 elapsedTime=0.563216 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:33.079119) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:33.079465) ========= script_repo_revision: f70b8561b4796217c3328dfc97b61e8ae934c1dd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:33.079903) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:33.080210) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 22:39:33.088380) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-19 22:39:33.088718) ========= /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=82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:39:33 INFO - MultiFileLogger online at 20160519 22:39:33 in /builds/slave/test 22:39:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:39:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:39:33 INFO - {'append_to_log': False, 22:39:33 INFO - 'base_work_dir': '/builds/slave/test', 22:39:33 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:39:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:39:33 INFO - 'branch': 'Mozilla-Inbound', 22:39:33 INFO - 'buildbot_json_path': 'buildprops.json', 22:39:33 INFO - 'config_files': ('talos/linux_config.py',), 22:39:33 INFO - 'default_actions': ('clobber', 22:39:33 INFO - 'read-buildbot-config', 22:39:33 INFO - 'download-and-extract', 22:39:33 INFO - 'populate-webroot', 22:39:33 INFO - 'create-virtualenv', 22:39:33 INFO - 'install', 22:39:33 INFO - 'run-tests'), 22:39:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:39:33 INFO - 'download_minidump_stackwalk': True, 22:39:33 INFO - 'download_symbols': 'ondemand', 22:39:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:39:33 INFO - 'tooltool.py': '/tools/tooltool.py', 22:39:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:39:33 INFO - '/tools/misc-python/virtualenv.py')}, 22:39:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:39:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:39:33 INFO - 'installer_path': 'installer.exe', 22:39:33 INFO - 'log_level': 'info', 22:39:33 INFO - 'log_name': 'talos', 22:39:33 INFO - 'log_to_console': True, 22:39:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:39:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:39:33 INFO - 'opt_config_files': (), 22:39:33 INFO - 'pip_index': False, 22:39:33 INFO - 'sps_profile': False, 22:39:33 INFO - 'sps_profile_interval': 0, 22:39:33 INFO - 'suite': 'dromaeojs', 22:39:33 INFO - 'system_bits': '32', 22:39:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:39:33 INFO - 'title': 'talos-linux64-ix-066', 22:39:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:39:33 INFO - 'use_talos_json': True, 22:39:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:39:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:39:33 INFO - 'work_dir': 'build'} 22:39:33 INFO - ##### 22:39:33 INFO - ##### Running clobber step. 22:39:33 INFO - ##### 22:39:33 INFO - Running pre-action listener: _resource_record_pre_action 22:39:33 INFO - Running main action method: clobber 22:39:33 INFO - rmtree: /builds/slave/test/build 22:39:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:39:34 INFO - Running post-action listener: _resource_record_post_action 22:39:34 INFO - ##### 22:39:34 INFO - ##### Finished clobber step (success) 22:39:34 INFO - ##### 22:39:34 INFO - ##### 22:39:34 INFO - ##### Running read-buildbot-config step. 22:39:34 INFO - ##### 22:39:34 INFO - Running pre-action listener: _resource_record_pre_action 22:39:34 INFO - Running main action method: read_buildbot_config 22:39:34 INFO - Using buildbot properties: 22:39:34 INFO - { 22:39:34 INFO - "project": "", 22:39:34 INFO - "product": "firefox", 22:39:34 INFO - "script_repo_revision": "production", 22:39:34 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:39:34 INFO - "repository": "", 22:39:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:39:34 INFO - "buildid": "20160519193117", 22:39:34 INFO - "pgo_build": "True", 22:39:34 INFO - "basedir": "/builds/slave/test", 22:39:34 INFO - "buildnumber": 950, 22:39:34 INFO - "slavename": "talos-linux64-ix-066", 22:39:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:39:34 INFO - "platform": "ubuntu64_hw", 22:39:34 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:39:34 INFO - "branch": "mozilla-inbound", 22:39:34 INFO - "repo_path": "integration/mozilla-inbound", 22:39:34 INFO - "revision": "f70b8561b4796217c3328dfc97b61e8ae934c1dd", 22:39:34 INFO - "stage_platform": "linux64-pgo", 22:39:34 INFO - "builduid": "8c66b5c99d874bcf977c2b0b9c5e6aa8", 22:39:34 INFO - "slavebuilddir": "test" 22:39:34 INFO - } 22:39:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:39:34 INFO - Running post-action listener: _resource_record_post_action 22:39:34 INFO - ##### 22:39:34 INFO - ##### Finished read-buildbot-config step (success) 22:39:34 INFO - ##### 22:39:34 INFO - ##### 22:39:34 INFO - ##### Running download-and-extract step. 22:39:34 INFO - ##### 22:39:34 INFO - Running pre-action listener: _resource_record_pre_action 22:39:34 INFO - Running main action method: download_and_extract 22:39:34 INFO - mkdir: /builds/slave/test/build/tests 22:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:34 INFO - https://queue.taskcluster.net/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:39:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:39:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:39:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/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 22:39:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/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 22:39:36 INFO - Downloaded 1361 bytes. 22:39:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:39:36 INFO - Using the following test package requirements: 22:39:36 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:39:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:39:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:39:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:39:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:39:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:39:36 INFO - u'jsshell-linux-x86_64.zip'], 22:39:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:39:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:39:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:39:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:39:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:39:36 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:39:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:39:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:39:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:39:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:39:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:39:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:39:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:36 INFO - https://queue.taskcluster.net/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:39:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:39:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:39:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/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 22:39:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/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 22:39:37 INFO - Downloaded 24388691 bytes. 22:39:37 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'] 22:39:37 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 22:39:38 INFO - Return code: 0 22:39:38 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 22:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:38 INFO - https://queue.taskcluster.net/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:39:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:39:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:39:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/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 22:39:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/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 22:39:40 INFO - Downloaded 24388691 bytes. 22:39:40 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'] 22:39:40 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 22:39:41 INFO - Return code: 0 22:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:41 INFO - https://queue.taskcluster.net/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:39:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:39:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:39:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/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 22:39:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/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 22:39:42 INFO - Downloaded 10840359 bytes. 22:39:42 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'] 22:39:42 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 22:39:42 INFO - Return code: 0 22:39: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')]} 22:39:42 INFO - https://queue.taskcluster.net/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:39:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:39:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:39:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/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 22:39:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IZeO1LCATPKzM9LkGHGE0w/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 22:39:44 INFO - Downloaded 58621247 bytes. 22:39:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:39:44 INFO - mkdir: /builds/slave/test/properties 22:39:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:39:44 INFO - Writing to file /builds/slave/test/properties/build_url 22:39:44 INFO - Contents: 22:39:44 INFO - build_url:https://queue.taskcluster.net/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:39:46 INFO - Running post-action listener: _resource_record_post_action 22:39:46 INFO - Running post-action listener: set_extra_try_arguments 22:39:46 INFO - ##### 22:39:46 INFO - ##### Finished download-and-extract step (success) 22:39:46 INFO - ##### 22:39:46 INFO - ##### 22:39:46 INFO - ##### Running populate-webroot step. 22:39:46 INFO - ##### 22:39:46 INFO - Running pre-action listener: _resource_record_pre_action 22:39:46 INFO - Running main action method: populate_webroot 22:39:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:39:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:39:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:39:46 INFO - '--tppagecycles', 22:39:46 INFO - '7'], 22:39:46 INFO - 'tests': ['tsvgm']}}, 22:39:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:39:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:39:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:39:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:39:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:39:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:39:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:46 INFO - 'talos_options': ['--disable-e10s'], 22:39:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:39:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:39:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:46 INFO - 'talos_options': ['--disable-e10s'], 22:39:46 INFO - 'tests': ['damp', 'tps']}, 22:39:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:46 INFO - 'tests': ['damp', 'tps']}, 22:39:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:39:46 INFO - 'tests': ['dromaeo_dom']}, 22:39:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:39:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:39:46 INFO - 'tests': ['a11yr', 22:39:46 INFO - 'ts_paint', 22:39:46 INFO - 'tpaint', 22:39:46 INFO - 'sessionrestore', 22:39:46 INFO - 'sessionrestore_no_auto_restore', 22:39:46 INFO - 'tabpaint']}, 22:39:46 INFO - 'other-e10s': {'tests': ['a11yr', 22:39:46 INFO - 'ts_paint', 22:39:46 INFO - 'tpaint', 22:39:46 INFO - 'sessionrestore', 22:39:46 INFO - 'sessionrestore_no_auto_restore', 22:39:46 INFO - 'tabpaint']}, 22:39:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:39:46 INFO - 'ts_paint', 22:39:46 INFO - 'tpaint', 22:39:46 INFO - 'sessionrestore', 22:39:46 INFO - 'sessionrestore_no_auto_restore', 22:39:46 INFO - 'tabpaint']}, 22:39:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:39:46 INFO - 'ts_paint', 22:39:46 INFO - 'tpaint', 22:39:46 INFO - 'sessionrestore', 22:39:46 INFO - 'sessionrestore_no_auto_restore', 22:39:46 INFO - 'tabpaint']}, 22:39:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:39:46 INFO - 'tests': ['a11yr', 22:39:46 INFO - 'ts_paint', 22:39:46 INFO - 'tpaint', 22:39:46 INFO - 'sessionrestore', 22:39:46 INFO - 'sessionrestore_no_auto_restore', 22:39:46 INFO - 'tabpaint']}, 22:39:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:39:46 INFO - 'tests': ['a11yr', 22:39:46 INFO - 'ts_paint', 22:39:46 INFO - 'tpaint', 22:39:46 INFO - 'sessionrestore', 22:39:46 INFO - 'sessionrestore_no_auto_restore', 22:39:46 INFO - 'tabpaint']}, 22:39:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:39:46 INFO - 'tests': ['tsvgx', 22:39:46 INFO - 'tsvgr_opacity', 22:39:46 INFO - 'tart', 22:39:46 INFO - 'tscrollx', 22:39:46 INFO - 'cart']}, 22:39:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:39:46 INFO - 'tsvgr_opacity', 22:39:46 INFO - 'tart', 22:39:46 INFO - 'tscrollx', 22:39:46 INFO - 'cart']}, 22:39:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:46 INFO - 'talos_options': ['--disable-e10s'], 22:39:46 INFO - 'tests': ['tp5o']}, 22:39:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:46 INFO - 'tests': ['tp5o']}, 22:39:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:46 INFO - 'talos_options': ['--disable-e10s', 22:39:46 INFO - '--xperf_path', 22:39:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:39:46 INFO - 'tests': ['tp5n']}, 22:39:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:39:46 INFO - 'talos_options': ['--xperf_path', 22:39:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:39:46 INFO - 'tests': ['tp5n']}}, 22:39:46 INFO - 'talos.zip': {'path': '', 22:39:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:39:46 INFO - Running post-action listener: _resource_record_post_action 22:39:46 INFO - ##### 22:39:46 INFO - ##### Finished populate-webroot step (success) 22:39:46 INFO - ##### 22:39:46 INFO - ##### 22:39:46 INFO - ##### Running create-virtualenv step. 22:39:46 INFO - ##### 22:39:46 INFO - Running pre-action listener: _resource_record_pre_action 22:39:46 INFO - Running main action method: create_virtualenv 22:39:46 INFO - Creating virtualenv /builds/slave/test/build/venv 22:39:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:39:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:39:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:39:46 INFO - Using real prefix '/usr' 22:39:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:39:46 INFO - Installing distribute.............................................................................................................................................................................................done. 22:39:48 INFO - Installing pip.................done. 22:39:48 INFO - Return code: 0 22:39:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:48 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 0x7f82fa2f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c9c0>, '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 0x1f6d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6dd50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:39:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:39:48 INFO - Using env: {'DISPLAY': ':0', 22:39:48 INFO - 'HOME': '/home/cltbld', 22:39:48 INFO - 'LANG': 'en_US.UTF-8', 22:39:48 INFO - 'LANGUAGE': 'en_US:en', 22:39:48 INFO - 'LOGNAME': 'cltbld', 22:39:48 INFO - 'MAIL': '/var/mail/cltbld', 22:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:48 INFO - 'MOZ_NO_REMOTE': '1', 22:39:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:48 INFO - 'NO_EM_RESTART': '1', 22:39:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:48 INFO - 'PWD': '/builds/slave/test', 22:39:48 INFO - 'SHELL': '/bin/bash', 22:39:48 INFO - 'SHLVL': '1', 22:39:48 INFO - 'TERM': 'linux', 22:39:48 INFO - 'TMOUT': '86400', 22:39:48 INFO - 'USER': 'cltbld', 22:39:48 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 22:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:48 INFO - '_': '/tools/buildbot/bin/python'} 22:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:39:49 INFO - Cleaning up... 22:39:49 INFO - Return code: 0 22:39:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:49 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 0x7f82fa2f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c9c0>, '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 0x1f6d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6dd50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:39:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:39:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:39:49 INFO - Using env: {'DISPLAY': ':0', 22:39:49 INFO - 'HOME': '/home/cltbld', 22:39:49 INFO - 'LANG': 'en_US.UTF-8', 22:39:49 INFO - 'LANGUAGE': 'en_US:en', 22:39:49 INFO - 'LOGNAME': 'cltbld', 22:39:49 INFO - 'MAIL': '/var/mail/cltbld', 22:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:49 INFO - 'MOZ_NO_REMOTE': '1', 22:39:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:49 INFO - 'NO_EM_RESTART': '1', 22:39:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:49 INFO - 'PWD': '/builds/slave/test', 22:39:49 INFO - 'SHELL': '/bin/bash', 22:39:49 INFO - 'SHLVL': '1', 22:39:49 INFO - 'TERM': 'linux', 22:39:49 INFO - 'TMOUT': '86400', 22:39:49 INFO - 'USER': 'cltbld', 22:39:49 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 22:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:49 INFO - '_': '/tools/buildbot/bin/python'} 22:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:49 INFO - Downloading/unpacking psutil>=3.1.1 22:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:39:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:39:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:39:51 INFO - Installing collected packages: psutil 22:39:51 INFO - Running setup.py install for psutil 22:39:51 INFO - building 'psutil._psutil_linux' extension 22:39:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:39:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:39:51 INFO - building 'psutil._psutil_posix' extension 22:39:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:39:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:39:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:39:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:39:51 INFO - Successfully installed psutil 22:39:51 INFO - Cleaning up... 22:39:51 INFO - Return code: 0 22:39:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:39: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')]} 22:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39: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')]} 22:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:51 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 0x7f82fa2f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c9c0>, '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 0x1f6d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6dd50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:39:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:39:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:39:51 INFO - Using env: {'DISPLAY': ':0', 22:39:51 INFO - 'HOME': '/home/cltbld', 22:39:51 INFO - 'LANG': 'en_US.UTF-8', 22:39:51 INFO - 'LANGUAGE': 'en_US:en', 22:39:51 INFO - 'LOGNAME': 'cltbld', 22:39:51 INFO - 'MAIL': '/var/mail/cltbld', 22:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:51 INFO - 'MOZ_NO_REMOTE': '1', 22:39:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:51 INFO - 'NO_EM_RESTART': '1', 22:39:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:51 INFO - 'PWD': '/builds/slave/test', 22:39:51 INFO - 'SHELL': '/bin/bash', 22:39:51 INFO - 'SHLVL': '1', 22:39:51 INFO - 'TERM': 'linux', 22:39:51 INFO - 'TMOUT': '86400', 22:39:51 INFO - 'USER': 'cltbld', 22:39:51 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 22:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:51 INFO - '_': '/tools/buildbot/bin/python'} 22:39:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:39: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 22:39: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 22:39: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 22:39: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 22:39: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 22:39: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 22:39:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:39:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:39:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:39:53 INFO - Installing collected packages: mozsystemmonitor 22:39:53 INFO - Running setup.py install for mozsystemmonitor 22:39:54 INFO - Successfully installed mozsystemmonitor 22:39:54 INFO - Cleaning up... 22:39:54 INFO - Return code: 0 22:39:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:39: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')]} 22:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39: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')]} 22:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:54 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 0x7f82fa2f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c9c0>, '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 0x1f6d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6dd50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:39:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:39:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:39:54 INFO - Using env: {'DISPLAY': ':0', 22:39:54 INFO - 'HOME': '/home/cltbld', 22:39:54 INFO - 'LANG': 'en_US.UTF-8', 22:39:54 INFO - 'LANGUAGE': 'en_US:en', 22:39:54 INFO - 'LOGNAME': 'cltbld', 22:39:54 INFO - 'MAIL': '/var/mail/cltbld', 22:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:54 INFO - 'MOZ_NO_REMOTE': '1', 22:39:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:54 INFO - 'NO_EM_RESTART': '1', 22:39:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:54 INFO - 'PWD': '/builds/slave/test', 22:39:54 INFO - 'SHELL': '/bin/bash', 22:39:54 INFO - 'SHLVL': '1', 22:39:54 INFO - 'TERM': 'linux', 22:39:54 INFO - 'TMOUT': '86400', 22:39:54 INFO - 'USER': 'cltbld', 22:39:54 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 22:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:54 INFO - '_': '/tools/buildbot/bin/python'} 22:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:54 INFO - Downloading/unpacking blobuploader==1.2.4 22:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:56 INFO - Downloading blobuploader-1.2.4.tar.gz 22:39:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:39:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:39:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:39:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:39:56 INFO - Downloading docopt-0.6.1.tar.gz 22:39:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:39:56 INFO - Installing collected packages: blobuploader, requests, docopt 22:39:56 INFO - Running setup.py install for blobuploader 22:39:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:39:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:39:57 INFO - Running setup.py install for requests 22:39:57 INFO - Running setup.py install for docopt 22:39:57 INFO - Successfully installed blobuploader requests docopt 22:39:57 INFO - Cleaning up... 22:39:57 INFO - Return code: 0 22:39:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:39: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')]} 22:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:39:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39: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')]} 22:39:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:39:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:39:57 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 0x7f82fa2f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c9c0>, '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 0x1f6d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6dd50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:39:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:39:57 INFO - Using env: {'DISPLAY': ':0', 22:39:57 INFO - 'HOME': '/home/cltbld', 22:39:57 INFO - 'LANG': 'en_US.UTF-8', 22:39:57 INFO - 'LANGUAGE': 'en_US:en', 22:39:57 INFO - 'LOGNAME': 'cltbld', 22:39:57 INFO - 'MAIL': '/var/mail/cltbld', 22:39:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:39:57 INFO - 'MOZ_NO_REMOTE': '1', 22:39:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:39:57 INFO - 'NO_EM_RESTART': '1', 22:39:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:39:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:39:57 INFO - 'PWD': '/builds/slave/test', 22:39:57 INFO - 'SHELL': '/bin/bash', 22:39:57 INFO - 'SHLVL': '1', 22:39:57 INFO - 'TERM': 'linux', 22:39:57 INFO - 'TMOUT': '86400', 22:39:57 INFO - 'USER': 'cltbld', 22:39:57 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 22:39:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:39:57 INFO - '_': '/tools/buildbot/bin/python'} 22:39:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:39:57 INFO - Running setup.py (path:/tmp/pip-wYU7C8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:39:57 INFO - Running setup.py (path:/tmp/pip-UtS8Wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:39:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:39:57 INFO - Running setup.py (path:/tmp/pip-XNa01Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:39:58 INFO - Running setup.py (path:/tmp/pip-gTBTz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:39:58 INFO - Running setup.py (path:/tmp/pip-ur9EDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:39:58 INFO - Running setup.py (path:/tmp/pip-uyOzfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:39:58 INFO - Running setup.py (path:/tmp/pip-KMRT3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:39:58 INFO - Running setup.py (path:/tmp/pip-dOtnzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:39:58 INFO - Running setup.py (path:/tmp/pip-vLJBLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:39:58 INFO - Running setup.py (path:/tmp/pip-EqrP8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:39:58 INFO - Running setup.py (path:/tmp/pip-_OZRz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:39:58 INFO - Running setup.py (path:/tmp/pip-NBeQ7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:39:58 INFO - Running setup.py (path:/tmp/pip-xml_da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:39:58 INFO - Running setup.py (path:/tmp/pip-e4d3HK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:39:58 INFO - Running setup.py (path:/tmp/pip-7KxhKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:39:58 INFO - Running setup.py (path:/tmp/pip-Dj55ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:39:58 INFO - Running setup.py (path:/tmp/pip-vwBpx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:39:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:39:59 INFO - Running setup.py install for manifestparser 22:39:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:39:59 INFO - Running setup.py install for mozcrash 22:39:59 INFO - Running setup.py install for mozdebug 22:39:59 INFO - Running setup.py install for mozdevice 22:39:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:39:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:39:59 INFO - Running setup.py install for mozfile 22:39:59 INFO - Running setup.py install for mozhttpd 22:39:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:39:59 INFO - Running setup.py install for mozinfo 22:39:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:39:59 INFO - Running setup.py install for mozInstall 22:39:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:39:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:39:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:39:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:39:59 INFO - Running setup.py install for mozleak 22:39:59 INFO - Running setup.py install for mozlog 22:40:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:40:00 INFO - Running setup.py install for moznetwork 22:40:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:40:00 INFO - Running setup.py install for mozprocess 22:40:00 INFO - Running setup.py install for mozprofile 22:40:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:40:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:40:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:40:00 INFO - Running setup.py install for mozrunner 22:40:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:40:00 INFO - Running setup.py install for mozscreenshot 22:40:00 INFO - Running setup.py install for moztest 22:40:00 INFO - Running setup.py install for mozversion 22:40:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:40:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:40:00 INFO - Cleaning up... 22:40:00 INFO - Return code: 0 22:40:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:40:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:40:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:40:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:40:00 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 0x7f82fa2f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c9c0>, '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 0x1f6d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6dd50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:40:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:40:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:40:00 INFO - Using env: {'DISPLAY': ':0', 22:40:00 INFO - 'HOME': '/home/cltbld', 22:40:00 INFO - 'LANG': 'en_US.UTF-8', 22:40:00 INFO - 'LANGUAGE': 'en_US:en', 22:40:00 INFO - 'LOGNAME': 'cltbld', 22:40:00 INFO - 'MAIL': '/var/mail/cltbld', 22:40:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:40:00 INFO - 'MOZ_NO_REMOTE': '1', 22:40:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:40:00 INFO - 'NO_EM_RESTART': '1', 22:40:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:40:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:40:00 INFO - 'PWD': '/builds/slave/test', 22:40:00 INFO - 'SHELL': '/bin/bash', 22:40:00 INFO - 'SHLVL': '1', 22:40:00 INFO - 'TERM': 'linux', 22:40:00 INFO - 'TMOUT': '86400', 22:40:00 INFO - 'USER': 'cltbld', 22:40:00 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 22:40:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:40:00 INFO - '_': '/tools/buildbot/bin/python'} 22:40:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:40:01 INFO - Running setup.py (path:/tmp/pip-_OWY2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:40:01 INFO - Running setup.py (path:/tmp/pip-F_o_rj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:40:01 INFO - Running setup.py (path:/tmp/pip-88nuh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:40:01 INFO - Running setup.py (path:/tmp/pip-klObUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:40:01 INFO - Running setup.py (path:/tmp/pip-dPU6Wl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:40:01 INFO - Running setup.py (path:/tmp/pip-22NzQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:40:01 INFO - Running setup.py (path:/tmp/pip-62LFAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:40:01 INFO - Running setup.py (path:/tmp/pip-0eImoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:40:01 INFO - Running setup.py (path:/tmp/pip-6gxh0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:40:01 INFO - Running setup.py (path:/tmp/pip-OLHj9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:40:01 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)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:40:01 INFO - Running setup.py (path:/tmp/pip-WdUFs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:40:01 INFO - Running setup.py (path:/tmp/pip-LHSD0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:40:01 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)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:40:01 INFO - Running setup.py (path:/tmp/pip-h52qJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:40:01 INFO - Running setup.py (path:/tmp/pip-VI5kpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:40:01 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)) 22:40:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:40:02 INFO - Running setup.py (path:/tmp/pip-ssXhYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:40:02 INFO - Running setup.py (path:/tmp/pip-xSFfZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:40:02 INFO - Running setup.py (path:/tmp/pip-CPNZkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:40:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:04 INFO - Downloading blessings-1.6.tar.gz 22:40:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:40:04 INFO - Installing collected packages: blessings 22:40:04 INFO - Running setup.py install for blessings 22:40:04 INFO - Successfully installed blessings 22:40:04 INFO - Cleaning up... 22:40:04 INFO - Return code: 0 22:40:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:40:04 INFO - Reading from file tmpfile_stdout 22:40:04 INFO - Current package versions: 22:40:04 INFO - argparse == 1.2.1 22:40:04 INFO - blessings == 1.6 22:40:04 INFO - blobuploader == 1.2.4 22:40:04 INFO - docopt == 0.6.1 22:40:04 INFO - manifestparser == 1.1 22:40:04 INFO - mozInstall == 1.12 22:40:04 INFO - mozcrash == 0.17 22:40:04 INFO - mozdebug == 0.1 22:40:04 INFO - mozdevice == 0.48 22:40:04 INFO - mozfile == 1.2 22:40:04 INFO - mozhttpd == 0.7 22:40:04 INFO - mozinfo == 0.9 22:40:04 INFO - mozleak == 0.1 22:40:04 INFO - mozlog == 3.2 22:40:04 INFO - moznetwork == 0.27 22:40:04 INFO - mozprocess == 0.22 22:40:04 INFO - mozprofile == 0.28 22:40:04 INFO - mozrunner == 6.11 22:40:04 INFO - mozscreenshot == 0.1 22:40:04 INFO - mozsystemmonitor == 0.3 22:40:04 INFO - moztest == 0.7 22:40:04 INFO - mozversion == 1.4 22:40:04 INFO - psutil == 3.1.1 22:40:04 INFO - requests == 1.2.3 22:40:04 INFO - wsgiref == 0.1.2 22:40:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:40:04 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 0x7f82fa2f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c9c0>, '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 0x1f6d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6dd50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:40:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:40:04 INFO - Using env: {'DISPLAY': ':0', 22:40:04 INFO - 'HOME': '/home/cltbld', 22:40:04 INFO - 'LANG': 'en_US.UTF-8', 22:40:04 INFO - 'LANGUAGE': 'en_US:en', 22:40:04 INFO - 'LOGNAME': 'cltbld', 22:40:04 INFO - 'MAIL': '/var/mail/cltbld', 22:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:40:04 INFO - 'MOZ_NO_REMOTE': '1', 22:40:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:40:04 INFO - 'NO_EM_RESTART': '1', 22:40:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:40:04 INFO - 'PWD': '/builds/slave/test', 22:40:04 INFO - 'SHELL': '/bin/bash', 22:40:04 INFO - 'SHLVL': '1', 22:40:04 INFO - 'TERM': 'linux', 22:40:04 INFO - 'TMOUT': '86400', 22:40:04 INFO - 'USER': 'cltbld', 22:40:04 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 22:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:40:04 INFO - '_': '/tools/buildbot/bin/python'} 22:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:40:04 INFO - Cleaning up... 22:40:04 INFO - Return code: 0 22:40:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:40:04 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 0x7f82fa2f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c9c0>, '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 0x1f6d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6dd50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:40:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:40:04 INFO - Using env: {'DISPLAY': ':0', 22:40:04 INFO - 'HOME': '/home/cltbld', 22:40:04 INFO - 'LANG': 'en_US.UTF-8', 22:40:04 INFO - 'LANGUAGE': 'en_US:en', 22:40:04 INFO - 'LOGNAME': 'cltbld', 22:40:04 INFO - 'MAIL': '/var/mail/cltbld', 22:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:40:04 INFO - 'MOZ_NO_REMOTE': '1', 22:40:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:40:04 INFO - 'NO_EM_RESTART': '1', 22:40:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:40:04 INFO - 'PWD': '/builds/slave/test', 22:40:04 INFO - 'SHELL': '/bin/bash', 22:40:04 INFO - 'SHLVL': '1', 22:40:04 INFO - 'TERM': 'linux', 22:40:04 INFO - 'TMOUT': '86400', 22:40:04 INFO - 'USER': 'cltbld', 22:40:04 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 22:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:40:04 INFO - '_': '/tools/buildbot/bin/python'} 22:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:40:05 INFO - Downloading/unpacking jsonschema 22:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:40:06 INFO - Downloading/unpacking functools32 (from jsonschema) 22:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:07 INFO - Downloading functools32-3.2.3-2.tar.gz 22:40:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:40:07 INFO - warning: no files found matching '*.txt' 22:40:07 INFO - no previously-included directories found matching 'build' 22:40:07 INFO - no previously-included directories found matching 'dist' 22:40:07 INFO - no previously-included directories found matching '.git*' 22:40:07 INFO - Installing collected packages: jsonschema, functools32 22:40:07 INFO - Running setup.py install for functools32 22:40:07 INFO - warning: no files found matching '*.txt' 22:40:07 INFO - no previously-included directories found matching 'build' 22:40:07 INFO - no previously-included directories found matching 'dist' 22:40:07 INFO - no previously-included directories found matching '.git*' 22:40:07 INFO - Successfully installed jsonschema functools32 22:40:07 INFO - Cleaning up... 22:40:07 INFO - Return code: 0 22:40:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:40:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:40:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:40:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:40:07 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 0x7f82fa2f4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c9c0>, '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 0x1f6d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6dd50>, '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': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:40:07 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 22:40:07 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 22:40:07 INFO - Using env: {'DISPLAY': ':0', 22:40:07 INFO - 'HOME': '/home/cltbld', 22:40:07 INFO - 'LANG': 'en_US.UTF-8', 22:40:07 INFO - 'LANGUAGE': 'en_US:en', 22:40:07 INFO - 'LOGNAME': 'cltbld', 22:40:07 INFO - 'MAIL': '/var/mail/cltbld', 22:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:40:07 INFO - 'MOZ_NO_REMOTE': '1', 22:40:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:40:07 INFO - 'NO_EM_RESTART': '1', 22:40:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:40:07 INFO - 'PWD': '/builds/slave/test', 22:40:07 INFO - 'SHELL': '/bin/bash', 22:40:07 INFO - 'SHLVL': '1', 22:40:07 INFO - 'TERM': 'linux', 22:40:07 INFO - 'TMOUT': '86400', 22:40:07 INFO - 'USER': 'cltbld', 22:40:07 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 22:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:40:07 INFO - '_': '/tools/buildbot/bin/python'} 22:40:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:40:07 INFO - Downloading/unpacking flake8 22:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:40:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:40:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:40:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:40:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:40:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:40:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:40:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:40:10 INFO - no previously-included directories found matching 'docs/_build' 22:40:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:40:10 INFO - Running setup.py install for pep8 22:40:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:40:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:40:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:40:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:40:10 INFO - no previously-included directories found matching 'docs/_build' 22:40:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:40:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:40:10 INFO - Cleaning up... 22:40:10 INFO - Return code: 0 22:40:10 INFO - Running post-action listener: _resource_record_post_action 22:40:10 INFO - Running post-action listener: _start_resource_monitoring 22:40:10 INFO - Starting resource monitoring. 22:40:10 INFO - ##### 22:40:10 INFO - ##### Finished create-virtualenv step (success) 22:40:10 INFO - ##### 22:40:10 INFO - ##### 22:40:10 INFO - ##### Running install step. 22:40:10 INFO - ##### 22:40:10 INFO - Running pre-action listener: _resource_record_pre_action 22:40:10 INFO - Running main action method: install 22:40:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:40:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:40:10 INFO - Reading from file tmpfile_stdout 22:40:10 INFO - Detecting whether we're running mozinstall >=1.0... 22:40:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:40:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:40:10 INFO - Reading from file tmpfile_stdout 22:40:10 INFO - Output received: 22:40:10 INFO - Usage: mozinstall [options] installer 22:40:10 INFO - Options: 22:40:10 INFO - -h, --help show this help message and exit 22:40:10 INFO - -d DEST, --destination=DEST 22:40:10 INFO - Directory to install application into. [default: 22:40:10 INFO - "/builds/slave/test"] 22:40:10 INFO - --app=APP Application being installed. [default: firefox] 22:40:10 INFO - mkdir: /builds/slave/test/build/application 22:40:10 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'] 22:40:10 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 22:40:27 INFO - Reading from file tmpfile_stdout 22:40:27 INFO - Output received: 22:40:27 INFO - /builds/slave/test/build/application/firefox/firefox 22:40:27 INFO - Running post-action listener: _resource_record_post_action 22:40:27 INFO - ##### 22:40:27 INFO - ##### Finished install step (success) 22:40:27 INFO - ##### 22:40:27 INFO - ##### 22:40:27 INFO - ##### Running run-tests step. 22:40:27 INFO - ##### 22:40:27 INFO - Running pre-action listener: _resource_record_pre_action 22:40:27 INFO - Running main action method: run_tests 22:40:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:40:27 INFO - Python 2.7.3 22:40:27 INFO - Return code: 0 22:40:27 INFO - grabbing minidump binary from tooltool 22:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:40:27 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 0x1f6c9c0>, '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 0x1f6d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6dd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:40:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:40:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:40:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:40:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:40:27 INFO - Return code: 0 22:40:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:40:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:40:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:40:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:40:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:40:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:40:28 INFO - Return code: 0 22:40:28 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/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:40:28 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/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:40:28 INFO - Using env: {'DISPLAY': ':0', 22:40:28 INFO - 'HOME': '/home/cltbld', 22:40:28 INFO - 'LANG': 'en_US.UTF-8', 22:40:28 INFO - 'LANGUAGE': 'en_US:en', 22:40:28 INFO - 'LOGNAME': 'cltbld', 22:40:28 INFO - 'MAIL': '/var/mail/cltbld', 22:40:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:40:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:40:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:40:28 INFO - 'MOZ_NO_REMOTE': '1', 22:40:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:40:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:40:28 INFO - 'NO_EM_RESTART': '1', 22:40:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:40:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:40:28 INFO - 'PWD': '/builds/slave/test', 22:40:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:40:28 INFO - 'SHELL': '/bin/bash', 22:40:28 INFO - 'SHLVL': '1', 22:40:28 INFO - 'TERM': 'linux', 22:40:28 INFO - 'TMOUT': '86400', 22:40:28 INFO - 'USER': 'cltbld', 22:40:28 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927', 22:40:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:40:28 INFO - '_': '/tools/buildbot/bin/python'} 22:40:28 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/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:40:28 INFO - mozversion application_buildid: 20160519193117 22:40:28 INFO - mozversion application_changeset: f70b8561b4796217c3328dfc97b61e8ae934c1dd 22:40:28 INFO - mozversion application_display_name: Nightly 22:40:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:40:28 INFO - mozversion application_name: Firefox 22:40:28 INFO - mozversion application_remotingname: firefox 22:40:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:40:28 INFO - mozversion application_vendor: Mozilla 22:40:28 INFO - mozversion application_version: 49.0a1 22:40:28 INFO - mozversion platform_buildid: 20160519193117 22:40:28 INFO - mozversion platform_changeset: f70b8561b4796217c3328dfc97b61e8ae934c1dd 22:40:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:40:28 INFO - mozversion platform_version: 49.0a1 22:40:28 INFO - using testdate: 1463722828 22:40:28 INFO - actual date: 1463722828 22:40:28 INFO - starting webserver on 'localhost:59264' 22:40:28 INFO - SUITE-START | Running 2 tests 22:40:28 INFO - TEST-START | dromaeo_css 22:40:28 INFO - operating with platform_type : linux_ 22:40:28 INFO - Initialising browser for dromaeo_css test... 22:40:28 INFO - TEST-INFO | started process 30122 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnnHpBh/profile http://localhost:59264/getInfo.html) 22:40:33 INFO - PROCESS | 30122 | __metrics Screen width/height:1600/1200 22:40:33 INFO - PROCESS | 30122 | colorDepth:24 22:40:33 INFO - PROCESS | 30122 | Browser inner width/height: 1024/697 22:40:33 INFO - PROCESS | 30122 | __metrics 22:40:34 ERROR - PROCESS | 30122 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:40:35 INFO - TEST-INFO | 30122: exit 0 22:40:35 INFO - Browser initialized. 22:40:35 INFO - Running cycle 1/1 for dromaeo_css test... 22:40:35 INFO - TEST-INFO | started process 30201 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnnHpBh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:40:47 INFO - PROCESS | 30201 | RSS: Main: 152641536 22:40:47 INFO - PROCESS | 30201 | 22:41:38 INFO - PROCESS | 30201 | 1463722898172 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:43:46 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:59264/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59264/tests/dromaeo/cssquery-ext.html) 22:43:47 INFO - PROCESS | 30201 | RSS: Main: 255418368 22:43:47 INFO - PROCESS | 30201 | 22:46:49 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:59264/tests/dromaeo/cssquery-ext.html (next: http://localhost:59264/tests/dromaeo/cssquery-jquery.html) 22:46:49 INFO - PROCESS | 30201 | RSS: Main: 243048448 22:46:49 INFO - PROCESS | 30201 | 22:49:52 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:59264/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59264/tests/dromaeo/cssquery-mootools.html) 22:49:52 INFO - PROCESS | 30201 | RSS: Main: 246370304 22:49:52 INFO - PROCESS | 30201 | 22:52:54 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:59264/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59264/tests/dromaeo/cssquery-prototype.html) 22:52:55 INFO - PROCESS | 30201 | RSS: Main: 235528192 22:52:55 INFO - PROCESS | 30201 | 22:55:57 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:59264/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59264/tests/dromaeo/cssquery-yui.html) 22:55:58 INFO - PROCESS | 30201 | RSS: Main: 221650944 22:55:58 INFO - PROCESS | 30201 | 22:59:01 INFO - PROCESS | 30201 | Cycle 1(1): loaded http://localhost:59264/tests/dromaeo/cssquery-yui.html (next: http://localhost:59264/tests/dromaeo/cssquery-dojo.html) 22:59:01 INFO - PROCESS | 30201 | RSS: Main: 307171328 22:59:01 INFO - PROCESS | 30201 | 22:59:01 INFO - PROCESS | 30201 | __start_tp_report 22:59:01 INFO - PROCESS | 30201 | _x_x_mozilla_page_load 22:59:01 INFO - PROCESS | 30201 | _x_x_mozilla_page_load_details 22:59:01 INFO - PROCESS | 30201 | |i|pagename|runs| 22:59:01 INFO - PROCESS | 30201 | |0;dojo.html;3127.32762456072;3145.370925814836;3166.1134882232977;3191.138392633987;3192.281736609263;5187.066328060957;5257.23770053342;5365.7585408656605;5461.371942226645;5467.945320546798;5354.22363757255;5420.078586640271;5454.61817672763;5526.364468086172;5544.91682624761;4869.342638743778;4963.404391473028;4966.379202599675;4973.104841128596;5105.3873535175835;4994.650374473788;5012.749362531877;5034.320366750479;5056.974715126419;5069.543741063304;4752.215884379073;4832.685875418087;4864.927026094611;4948.1093403187415;4955.578775804061;4956.231784073474;4971.552560269557;4994.275830004629;5024.472430394792;5046.142155833517;4805.927911081337;4985.775640096168;4991.925121123186;5011.2483127530795;5039.84880453587;5253.973730131325;5311.070562651521;5323.228131920889;5384.057789886754;5385.165299378564;5758.481736643722;5791.102379131242;5809.651420914759;5816.534677225812;5816.738246778863;5680.147977803321;5780.046292361755;5856.882862342772;5871.500922421599;5872.941270587325;5465.344158700942;5550.555955523549;5551.805686800943;5555.972220138873;5561.054620714448;6050.42520960508;6055.8486037848525;6061.969690151521;6062.605930614496;6067.484263837623;5459.317585301837;5689.459501347365;5712.514436272881;5816.592838501264;5829.387914268941;5854.677992710356;5916.467517923407;5921.111833225051;5925.762969481259;5936.436038576328;5807.419258074243;5844.1818145459665;5846.941530584725;5854.531637468992;5855.677937713382;5499.092649712753;5639.464250896158;5664.405237450091;5688.687122208318;5824.650520968756;4357.063231405263;4360.280553708602;4363.7381757094045;4372.584604462571;4396.846110386121;3896.0649444133205;3902.31709450845;3912.6869850412468;3942.6648734857285;3956.6834653227675;5337.306150200449;5443.755031023614;5450.890982180414;5458.153986132156;5501.504864562208;5843.620164689281;5844.649321040752;5855.238818953509;5859.853503662443;5888.5289176865745;5496.532794712494;5512.972435137799;5527.419620939824;5528.530074943595;5528.75120619573;4812.711237325771;4931.161702510582;4986.301917731521;4993.575546078624;5004.449510553836;5962.314333851663;6006.909896351558;6014.849628759316;6017.247844019497;6018.368071352533;5614.69119198448;5819.970900145472;5823.068309070443;5843.29880414353;5843.853903652443;5879.853003674943;5924.229850119629;5937.19847820556;5974.1934838796205;5992.221011268507;5657.264555607745;5687.544996400361;5691.914621280767;5700.116481945305;5706.42935706426;5579.302587176603;5719.914201286901;5816.2438882946635;5828.184054232495;5831.591788574759;5607.355154157324;5669.603127781016;5671.631343962629;5672.347680016851;5725.742341594721;5611.915821262603;5637.633553818989;5651.943480565306;5652.802151924663;5664.943350566442;5445.673259604437;5572.387037425961;5577.247071645276;5577.972110139423;5593.860153496195;5790.073588226032;5835.4748072673665;5836.941630583639;5840.678762668094;5842.883142337045;5640.407757185437;5681.573881958787;5685.118806584986;5688.943110569007;5729.312482502127 22:59:01 INFO - PROCESS | 30201 | |1;ext.html;612.249993757647;630.8801327747728;634.7238951056288;644.4425571880323;662.2251965200714;33746;36462.63537364629;36530.63469365309;43729.56270437295;44216.55783442165;45442.772786136105;46190.53809461913;46436.76781616096;46524.302135467995;46642.7667861661;8697.608607612641;8884.067172946845;8955.955220223923;8969.372143949926;8971.506567138806;8813.823723525526;8951.686690965818;8965.23795477382;8966;8975.596098175598;8983.10168983103;9054.728358149234;9061.682841100563;9064.093590640916;9071.138241867046;8887.555622218895;8959;8966.417182883157;8969.237614802749;8970.551472426385;8821.426607270507;8902.421342612774;8910.50992195429;8911.910880891237;8915.777105572413;42910.57089429097;43441;43454.56545434537;43540.78229608863;43732.3440148398;3581.2658405026905;3629.546306711661;3710;3788.981055094707;4153.273177193979;31373;33198.6680133198;33715;33774.14242216677;33927;4310.52584215736;4371.103923695635;4384.473863136413;4483.349914262446;4491.955080449186;352808;362309;363465;364159;366096.9999999973;63019.36980630135;63163.6841815788;63836.68081659562;63936.92599203068;64011.67994159999;66856;67641.66179169172;67904;67938.30154246085;68047.65976170088;17147.828521714873;17313.7402938956;17322.566935826704;17325.56686082858;17340.393086241922;12437.813432798515;12533.561325353568;12537;12551.937240313922;12560.497580096693;17771.696074900974;20324.390268291976;20382.490437739176;20649.17403303881;20668.793312066988;6284.622922624566;7161.89257161143;7463.664135113824;7494.21310762373;7658.349040331523;975.7219192530094;990.4601991914424;1048.7168464514539;1065.3661071662348;1125.7861006408757;31828.204294892814;33068.3386332272;33169.83415082957;33349.33301334008;33869.66130338665;1133.7165708572857;1134.9092072634169;1184.5557915781583;1193.1349771415655;1214.0409076829383;17154.39959601408;17429.302827886997;17430.302787888595;17511.211995460235;17704.468865934043;533350;542424;549234.5076549263;549872;860969;59881;60280.39719602835;60508.394916051155;60641.69679151576;60661.69669151626;1273.2360583649736;1335.5726167626267;1383.391307824554;1396.9930150349182;1408.5422237772764;29707.405851883275;33584.49623255604;33620.49569256512;33635;33986.490202646484;51332.48667513426;51692.74153629358;52195.217071743195;52201.21698174607;52277.7386113067;429777;433710;434406;435235;436103;26260.212193634223;28261;28624.284392889513;29272.70727292785;29340.85329573424;48169.759151204016;48441.27338090002;48738.26892596685;49866.50133498764;49912.75043624759;40129.39805902972;40578.18843623052;41342;41566;41733.37399938943;41029.589704102575;41838.37242441305;42113;42194.03798373357;42432.78783606062;40756.1848763029;40987.59012409838;41176.1764764709;42124.368134477394;42432.78783606062;36203.45694814527;37017.259654806214;37062.25875482529;37526.249475010896;37702.245955080194;28458.715412846435;28466.146015619564;28492.57261141043;28712.282192945342;28882.71117288884 22:59:01 INFO - PROCESS | 30201 | |2;jquery.html;388.6385661334957;397.83887525552154;404.8380647740905;408.43227913200656;413.84593192433385;14079;17854.82145178548;18066.187021584028;19620;19631.31290404836;15017.62455938602;17887.910560447213;18516.259349626034;18905.527361815963;19258.51853703658;5465.808696695606;5678.659280443166;5703.5152012079025;5716.771329146829;5718.285214348207;4024.396340548926;5582.748776305056;5664.688442135681;6003.8198854034445;6272.3100458949475;6334.176557047577;6351.301356850743;6354.237491501003;6355.523335749815;6360.395762402564;6143;6276.68616569172;6345.49236061114;6352.5553211275;6366.045093236005;5560.499555040065;5561.304836895388;5562.916556251659;5576.804811831566;6475.352464753534;25253.62119568208;25678;25862.6120608191;26483.73516264851;26560.73439265602;2456.336789066942;2614.5947378156416;2625.488029834183;2645.2593273883344;3191.4414977378865;15272.770908436383;15569.610759730984;15610.141442220673;16070.839291607052;16610.169491525376;2112.6197284488785;2115.883626400547;2163.621366260898;2204.239537359608;2232.2745107839855;59244.70377648127;59929.40070599282;60241.6987915062;60667.39332606706;60791.69604151995;17679.381221657437;17866.30270124336;18034.639307213783;18252.083531229295;18366.449006529827;33083.33833323368;33123.33753324968;33417.83291083529;33464.1633959153;33470;9776.755581110387;10114.645987390553;10215.426340743506;10249.641262555775;10393.792124157475;8578.056413794478;8645.524496152771;8688.78278043054;8740.562971851383;8789.165029322205;7391.445641576904;8415.621297041647;9055.81888362229;9117.270618350516;9310.767230819283;3187.6971687689634;3211.2563616405364;3325.93348133036;3488.389531831919;3550.797657411388;960.6061514779046;1002.0230275481348;1012.7721262715977;1027.0448482910965;1105.7415030924926;19088.904555477133;19581;19950.70073948892;20085;20258;1021.1014307409581;1027.2244455436098;1028.9691309260734;1040.6825918095053;1044.2116202267391;10585.735356616146;11122.666320010412;11161.776764464828;11250.268732532359;11257.099432045417;454366;455679;457400;457827;458424;85409;86057;87760;88165;89201.55399223093;990.4156547636928;1022.7136401807505;1028.3161697471248;1049.3441599000626;1053.2416660004785;17222;17245.89253666229;17420.128993550527;17604;17769.111544422987;32770.01689949305;37152.256954861296;37380.439293410076;41116.794416027726;41842.790786047095;51769.22346164735;52478.737606311726;54147.729261353445;57369.713151435644;57433.42566574448;7739.922600774145;8065.1934806518875;8738.606762695821;8796.91203087961;9039.59321830506;13540.255285959367;13929.721405572034;14000.409310312156;14213.50252741149;14316;12163.939180304042;12164.939175304067;12199.939000305296;12593.557590857996;12629.93685031569;12375.257484550955;12433.191842530568;12456.564020259248;12462.127651064338;12734.363281836057;12297.631071067883;12804.335436393209;12823.166494178222;12982.221066736027;13105.934470327587;8063.83872322562;8170.959145204236;8201.71294004707;8229.629666664894;8381.916180838114;12110.515579376726;12126.237549576896;12215.69460763488;12744.10791244604;12817 22:59:01 INFO - PROCESS | 30201 | |3;mootools.html;1332.5669157523807;1458.445790599572;1500.3323521033137;1600.7518834580637;1781.3676144968545;8233.464824786384;8622.18089281518;8622.482651040935;8793.95603021984;8815.955920220405;8557.614907329169;8723.825523489526;8753.95623021884;8758.211760941515;8759.693410730626;2031.4108908416542;2212.6459766437374;2277.601419751545;2323.9883800580974;2351.3651314145172;2295.5982703026903;2301.045066297493;2301.171578231835;2304.0553373117027;2304.585174668559;2318.6058370077126;2321.1527792355755;2329.6738456616085;2346.7066616672914;2351.318117745852;1964.3419454482694;2159.427751645815;2183.159483598818;2217.02450921594;2219.5117074243803;2250.3024062540667;2258.8870556472275;2275.9317220483413;2276.2260831317267;2276.965845512302;8761.21149096578;8804.603792829274;8809.471431714117;8812.823743525094;8825.558722063872;5143.614228932845;5189.974050129763;5196.402413722432;5207.60942929278;5207.973960130213;3168;3201.951970720441;3254.5931758530182;3286.96713032869;3297.472404415282;1011.145581983223;1011.989880101197;1027.7944411117805;1031.1235449867627;1034.0745033195249;11937.462814173383;11950.402479876058;12003.699907502383;12009.339486328248;12009.93995030028;4478.283474644041;4528.637708983273;4564.292534657134;4581.221192397301;4646.86059418218;10002.349847259904;10005.79988400228;10016.449095299682;10047.899521004843;10062;5124.974375128175;5190.92213616796;5193.662411943212;5193.662411943287;5199.532042116152;4266.807993640294;4590.839320623762;4721.268203428474;4726.196546587088;4767;3882.301185786529;3951.269015232191;4019.91960160801;4054.6958978076764;4058.7158898877387;1211.787937111002;1212.8847759462838;1228.5147366790068;1231.7290196156832;1239.913206075566;892.6340200517886;952.6094301336558;1012.7923775626126;1039.4750650921237;1053.1101219469535;6001.699915004232;6052.878942421127;6194;6256.061590761422;6269.592476489013;958.6692591056075;962.6678795815433;976.4108246071345;992.1665800727424;992.1814503034881;4224.239636865394;4284.957150428519;4285.871423857289;4295.37717031035;4330.480342358937;10687.30532515384;10918.727031824268;11009.6146634869;11038.779224415466;11045.83431248532;10852.077573406192;10941.890581094247;10951.178661600412;10952.616658416917;10954.397508137128;7765.378769698411;8082.757517274491;8194.262516373556;8220.054693710299;8247;5989.401059893976;6035.245594300713;6095.664738439428;6147.0779383092895;6348.5238607105575;9840.458774767456;9858.901410985798;9981;10039.498025098861;10078.294519383573;9979.301448898797;10168.49157542105;10212;10286.434246116536;10293.691189264335;7200.783976480714;7629.54222746637;7830.686772529035;7859.607019649109;8016;8728.476291422536;8740.257078148428;8777.166169213915;8809.515476648847;8824.823503530022;7547.547147171187;7669.271419215165;7830.1386847447875;7876.527408355517;8003.559804210824;7624.466287359832;7647.005889234364;7712.614369281626;7826.452148349561;7857.371410287049;7120.394766444703;7148.392386646985;7186.065811444479;7187.964060179875;7206.56760594366;5253.290805741329;5371.650842695212;5460.099083651312;5464.480874316933;5527.972360138174;5307.097793375058;5472.069748142746;5502.119660854244;5587.3015873015875;5596.664200148004 22:59:01 INFO - PROCESS | 30201 | |4;prototype.html;325.50523204728796;331.57227176941745;341.28671077448143;341.6975976261009;343.9896803095905;5279.647423885831;5340.519353258211;5342.973285133569;5350.5184533391985;5431.511163995245;5063.215201643742;5120.820771273006;5249.790008399659;5265.236540701596;5290.629655924087;4124.381342798574;4166.854160104397;4169.812358443862;4193.517745459266;4197.790110494486;4191.853285135021;4195.4545909031785;4206.810693518783;4215.346621273692;4222.493300803908;3977.4630424892703;4000.799960002003;4139.275626765319;4156.750594964296;4161.105362347094;4114.670826333886;4132.132252227036;4133.9173216535655;4140.358244472111;4147.937780933288;3691.7600355977042;3718.8140592970512;3724.478572999782;3727.9068023299633;3753.887383378503;5052.242163675442;5086.923696144524;5140.280360749498;5169.948300516984;5210.036143313461;4730.408698912636;4737.407824021997;4766.808297925519;4798.0883632110135;4798.832040878552;5458.890822183574;5470.480304371078;5481.177823326493;5484.533814625762;5497.752601132958;3951.4665520154986;4036.354183330653;4054.3918412238104;4063.167050754588;4065.695072869517;5672.5178359839465;5697.800576979786;5703.3441154267375;5740.54075673945;5741.196232527408;4141.461609990682;4154.376843473503;4189.49726032878;4189.748615083044;4195.895102622459;4113.4035564843525;4185.288500954845;4195.286801243796;4245;4245.469316335458;4685.554872287127;4709.105269998689;4728.95962888164;4732.834350797774;4752.738599377068;5498.725063746797;5517;5522.088855338824;5534.1975413565615;5540.889182216333;1382.2121390807345;1402.6007378469894;1434.483585909072;1444.241773069147;1464.6704491489543;987.6888780034266;1002.173207104142;1019.261035749076;1030.2427715629008;1033.6278939581744;5278.551323137576;5292.126799078109;5300.826661068943;5308.203769434615;5311.601629877775;5574.2998385492;5587.8486782589;5606.579506537026;5613.326400831927;5626.831195064155;5124.590032797368;5267.604929630293;5290.5238528531845;5320.547753441001;5345.305110335709;3000.0399872041176;3041.330907200412;3043.269615292314;3049.87800487978;3050.3289276359164;5724.341700704473;5836.708164591753;5852.4147585241135;5853.541512564733;5860.970695146497;5626.746796394172;5715.314162300468;5742.569307301969;5750.051241545182;5752.568557358131;5712.657525481509;5736.483716465538;5739.282589676374;5742.827715168552;5794.82615521535;5382.623216374816;5538.335399752055;5554.194641776839;5599.0683214291;5616.241807356118;5463.907218556385;5512.586556008235;5547.556195504269;5548.140038294071;5566.137248726453;5779.595428319977;5791.581062639681;5794.739236734441;5798.159266906359;5828.329742079715;3834.616538346255;4079.1637714269095;4083.7549747015273;4092.9386059209733;4097.528784189856;5704.2014118024335;5704.429557044263;5707.1724599933605;5766.817802350424;5792.420757924174;5433.185022246695;5435;5571.272905399339;5611.803586874603;5655.4910058094965;5521.889562208814;5563.304586926634;5567.721613919207;5591.384947655836;5620.353659329229;5319.366913377903;5458.645188062763;5520.957245819072;5536.972315138399;5539.424167761849;5614.831555053355;5633.8591535211945;5642.210090587239;5644.576656750843;5690.687012214368;5467.972660136674;5554.08357620988;5569.248151499496;5583.302087239095;5590.608657394106 22:59:01 INFO - PROCESS | 30201 | |5;yui.html;832.4880198677813;839.08539691736;839.1440730454932;846.1580727176458;848.160321281931;14681.119132852024;15184.39262429503;15436.15101169434;15458.07251564907;15483.14842683652;13318.733625327488;14164.575062748108;14205.71588568228;14263.215523146222;14295.070820396693;675.1021141881287;795.7493389582291;808.1191501263606;816.008549612221;827.3505298340804;815.7308088330836;816.6692489541745;817.6116344736262;817.6851912013888;820.8645573480399;855.6748435594496;858.6307887608326;861.2937391339104;862.5169904853271;863.4646519158099;706.9611171385546;838.4214891724752;857.4469467193657;858.2404571953804;864.394923553515;624.9843753906143;626.0421555020826;626.7806267806277;627.7489004398232;627.7802769030849;14328.713425731532;14333.713325733426;14357;14369.281535923163;14406.279686015658;13111.147775394664;13489.111524386593;13619.523316683868;13703.794443083361;13751.2659206673;12006.579769708105;12019.93990030053;12025.699357516045;12044.337561434118;12279.079069069856;4390.824367025315;4434.578715022068;4472.84345047925;4495.842645507391;4542.341360502742;51865.48134518644;53112;53241.2013819793;53434.46565534334;54071;10145.239107067;10167.440790756431;10317.587296508205;10432.321899076536;10618.893811061791;20344.69482957757;20363.898180509004;20380.694289585666;20404.591908161754;20425.182992680424;8188.222118898695;8195.139510351446;8248.422610417214;8386.650957231564;8474.74575762728;4482.48888536826;4579.335996280587;4663.323818046364;4744.1934871072;4762.903064789871;3365.74756893234;3484.47732840076;3485.4597537381746;3493.2314890724;3499.177693242086;654.5679851298121;677.3260605697362;708.412018025038;711.4699548836151;724.9673764680497;631.8767840271195;687.814290141659;752.2477522477523;792.5786721565557;793.1473665809278;9517.191038761644;9594.760130996641;9896.406215627085;9904.702858914245;9916.206703463706;772.7681695491331;775.3642013548836;787.7164220880478;789.9526028438312;800.0799081056831;4632.559906808848;4762;4781.545753153515;4785.210440277316;4810.63920205986;71787;73485.6325718368;73588;74104.25895741081;74516.62741686257;57600;59362;59955;60186.39813601896;60688.69655651782;53937.46062539403;54674.72662636662;54910.72544637331;55182.17226741602;55209.72395137999;8871.467711937305;9018;9047.592858321523;9083.409578377385;9139.268858491438;36266;36353.63646363608;36898.44652330271;37021.44467832931;37474;45142.77428612836;48461.27308090311;49475.50524494853;49790.50209497858;50785.746071270885;11055.22613417053;11158.218924675519;11936.164468487123;12018.459169337226;12056;13440.596782096552;13459.730805384033;13586.252756098509;13594.320283985564;13664.795028074388;12696.301703406401;12782.48870045225;12921.224726516439;13322.733545328845;13339.733205335644;12354.25874447536;12427.254364738144;12460.813087803508;12663.176893501894;13304.933475332562;12101.69745756363;12317.692057698629;12661.556845510362;12702;13057.804132938203;9101.726948191565;9154.588043537924;9200.21798147165;9341.906580934376;9648.276379271723;9869;10575.62985295511;10725.785484290427;10775.191860610328;10809.891901080888 22:59:01 INFO - PROCESS | 30201 | __end_tp_report 22:59:01 INFO - PROCESS | 30201 | __start_cc_report 22:59:01 INFO - PROCESS | 30201 | _x_x_mozilla_cycle_collect,903 22:59:01 INFO - PROCESS | 30201 | __end_cc_report 22:59:01 INFO - PROCESS | 30201 | __startTimestamp1463723941520__endTimestamp 22:59:01 INFO - PROCESS | 30201 | 22:59:01 INFO - PROCESS | 30201 | ------- Summary: start ------- 22:59:01 INFO - PROCESS | 30201 | Number of tests: 6 22:59:01 INFO - PROCESS | 30201 | 22:59:01 INFO - PROCESS | 30201 | [#0] dojo.html Cycles:165 Average:5396.56 Median:5593.86 stddev:619.29 (11.1%) stddev-sans-first:595.05 22:59:01 INFO - PROCESS | 30201 | Values: 3127.3 3145.4 3166.1 3191.1 3192.3 5187.1 5257.2 5365.8 5461.4 5467.9 5354.2 5420.1 5454.6 5526.4 5544.9 4869.3 4963.4 4966.4 4973.1 5105.4 4994.7 5012.7 5034.3 5057.0 5069.5 4752.2 4832.7 4864.9 4948.1 4955.6 4956.2 4971.6 4994.3 5024.5 5046.1 4805.9 4985.8 4991.9 5011.2 5039.8 5254.0 5311.1 5323.2 5384.1 5385.2 5758.5 5791.1 5809.7 5816.5 5816.7 5680.1 5780.0 5856.9 5871.5 5872.9 5465.3 5550.6 5551.8 5556.0 5561.1 6050.4 6055.8 6062.0 6062.6 6067.5 5459.3 5689.5 5712.5 5816.6 5829.4 5854.7 5916.5 5921.1 5925.8 5936.4 5807.4 5844.2 5846.9 5854.5 5855.7 5499.1 5639.5 5664.4 5688.7 5824.7 4357.1 4360.3 4363.7 4372.6 4396.8 3896.1 3902.3 3912.7 3942.7 3956.7 5337.3 5443.8 5450.9 5458.2 5501.5 5843.6 5844.6 5855.2 5859.9 5888.5 5496.5 5513.0 5527.4 5528.5 5528.8 4812.7 4931.2 4986.3 4993.6 5004.4 5962.3 6006.9 6014.8 6017.2 6018.4 5614.7 5820.0 5823.1 5843.3 5843.9 5879.9 5924.2 5937.2 5974.2 5992.2 5657.3 5687.5 5691.9 5700.1 5706.4 5579.3 5719.9 5816.2 5828.2 5831.6 5607.4 5669.6 5671.6 5672.3 5725.7 5611.9 5637.6 5651.9 5652.8 5664.9 5445.7 5572.4 5577.2 5578.0 5593.9 5790.1 5835.5 5836.9 5840.7 5842.9 5640.4 5681.6 5685.1 5688.9 5729.3 22:59:01 INFO - PROCESS | 30201 | 22:59:01 INFO - PROCESS | 30201 | [#1] ext.html Cycles:180 Average:63146.19 Median:42671.68 stddev:129070.88 (302.5%) stddev-sans-first:129347.56 22:59:01 INFO - PROCESS | 30201 | Values: 612.2 630.9 634.7 644.4 662.2 33746.0 36462.6 36530.6 43729.6 44216.6 45442.8 46190.5 46436.8 46524.3 46642.8 8697.6 8884.1 8956.0 8969.4 8971.5 8813.8 8951.7 8965.2 8966.0 8975.6 8983.1 9054.7 9061.7 9064.1 9071.1 8887.6 8959.0 8966.4 8969.2 8970.6 8821.4 8902.4 8910.5 8911.9 8915.8 42910.6 43441.0 43454.6 43540.8 43732.3 3581.3 3629.5 3710.0 3789.0 4153.3 31373.0 33198.7 33715.0 33774.1 33927.0 4310.5 4371.1 4384.5 4483.3 4492.0 352808.0 362309.0 363465.0 364159.0 366097.0 63019.4 63163.7 63836.7 63936.9 64011.7 66856.0 67641.7 67904.0 67938.3 68047.7 17147.8 17313.7 17322.6 17325.6 17340.4 12437.8 12533.6 12537.0 12551.9 12560.5 17771.7 20324.4 20382.5 20649.2 20668.8 6284.6 7161.9 7463.7 7494.2 7658.3 975.7 990.5 1048.7 1065.4 1125.8 31828.2 33068.3 33169.8 33349.3 33869.7 1133.7 1134.9 1184.6 1193.1 1214.0 17154.4 17429.3 17430.3 17511.2 17704.5 533350.0 542424.0 549234.5 549872.0 860969.0 59881.0 60280.4 60508.4 60641.7 60661.7 1273.2 1335.6 1383.4 1397.0 1408.5 29707.4 33584.5 33620.5 33635.0 33986.5 51332.5 51692.7 52195.2 52201.2 52277.7 429777.0 433710.0 434406.0 435235.0 436103.0 26260.2 28261.0 28624.3 29272.7 29340.9 48169.8 48441.3 48738.3 49866.5 49912.8 40129.4 40578.2 41342.0 41566.0 41733.4 41029.6 41838.4 42113.0 42194.0 42432.8 40756.2 40987.6 41176.2 42124.4 42432.8 36203.5 37017.3 37062.3 37526.2 37702.2 28458.7 28466.1 28492.6 28712.3 28882.7 22:59:01 INFO - PROCESS | 30201 | 22:59:01 INFO - PROCESS | 30201 | [#2] jquery.html Cycles:180 Average:28435.04 Median:14874.22 stddev:74827.07 (503.1%) stddev-sans-first:75007.35 22:59:01 INFO - PROCESS | 30201 | Values: 388.6 397.8 404.8 408.4 413.8 14079.0 17854.8 18066.2 19620.0 19631.3 15017.6 17887.9 18516.3 18905.5 19258.5 5465.8 5678.7 5703.5 5716.8 5718.3 4024.4 5582.7 5664.7 6003.8 6272.3 6334.2 6351.3 6354.2 6355.5 6360.4 6143.0 6276.7 6345.5 6352.6 6366.0 5560.5 5561.3 5562.9 5576.8 6475.4 25253.6 25678.0 25862.6 26483.7 26560.7 2456.3 2614.6 2625.5 2645.3 3191.4 15272.8 15569.6 15610.1 16070.8 16610.2 2112.6 2115.9 2163.6 2204.2 2232.3 59244.7 59929.4 60241.7 60667.4 60791.7 17679.4 17866.3 18034.6 18252.1 18366.4 33083.3 33123.3 33417.8 33464.2 33470.0 9776.8 10114.6 10215.4 10249.6 10393.8 8578.1 8645.5 8688.8 8740.6 8789.2 7391.4 8415.6 9055.8 9117.3 9310.8 3187.7 3211.3 3325.9 3488.4 3550.8 960.6 1002.0 1012.8 1027.0 1105.7 19088.9 19581.0 19950.7 20085.0 20258.0 1021.1 1027.2 1029.0 1040.7 1044.2 10585.7 11122.7 11161.8 11250.3 11257.1 454366.0 455679.0 457400.0 457827.0 458424.0 85409.0 86057.0 87760.0 88165.0 89201.6 990.4 1022.7 1028.3 1049.3 1053.2 17222.0 17245.9 17420.1 17604.0 17769.1 32770.0 37152.3 37380.4 41116.8 41842.8 51769.2 52478.7 54147.7 57369.7 57433.4 7739.9 8065.2 8738.6 8796.9 9039.6 13540.3 13929.7 14000.4 14213.5 14316.0 12163.9 12164.9 12199.9 12593.6 12629.9 12375.3 12433.2 12456.6 12462.1 12734.4 12297.6 12804.3 12823.2 12982.2 13105.9 8063.8 8171.0 8201.7 8229.6 8381.9 12110.5 12126.2 12215.7 12744.1 12817.0 22:59:01 INFO - PROCESS | 30201 | 22:59:01 INFO - PROCESS | 30201 | [#3] mootools.html Cycles:180 Average:5797.84 Median:4684.06 stddev:3283.24 (70.1%) stddev-sans-first:3275.30 22:59:01 INFO - PROCESS | 30201 | Values: 1332.6 1458.4 1500.3 1600.8 1781.4 8233.5 8622.2 8622.5 8794.0 8816.0 8557.6 8723.8 8754.0 8758.2 8759.7 2031.4 2212.6 2277.6 2324.0 2351.4 2295.6 2301.0 2301.2 2304.1 2304.6 2318.6 2321.2 2329.7 2346.7 2351.3 1964.3 2159.4 2183.2 2217.0 2219.5 2250.3 2258.9 2275.9 2276.2 2277.0 8761.2 8804.6 8809.5 8812.8 8825.6 5143.6 5190.0 5196.4 5207.6 5208.0 3168.0 3202.0 3254.6 3287.0 3297.5 1011.1 1012.0 1027.8 1031.1 1034.1 11937.5 11950.4 12003.7 12009.3 12009.9 4478.3 4528.6 4564.3 4581.2 4646.9 10002.3 10005.8 10016.4 10047.9 10062.0 5125.0 5190.9 5193.7 5193.7 5199.5 4266.8 4590.8 4721.3 4726.2 4767.0 3882.3 3951.3 4019.9 4054.7 4058.7 1211.8 1212.9 1228.5 1231.7 1239.9 892.6 952.6 1012.8 1039.5 1053.1 6001.7 6052.9 6194.0 6256.1 6269.6 958.7 962.7 976.4 992.2 992.2 4224.2 4285.0 4285.9 4295.4 4330.5 10687.3 10918.7 11009.6 11038.8 11045.8 10852.1 10941.9 10951.2 10952.6 10954.4 7765.4 8082.8 8194.3 8220.1 8247.0 5989.4 6035.2 6095.7 6147.1 6348.5 9840.5 9858.9 9981.0 10039.5 10078.3 9979.3 10168.5 10212.0 10286.4 10293.7 7200.8 7629.5 7830.7 7859.6 8016.0 8728.5 8740.3 8777.2 8809.5 8824.8 7547.5 7669.3 7830.1 7876.5 8003.6 7624.5 7647.0 7712.6 7826.5 7857.4 7120.4 7148.4 7186.1 7188.0 7206.6 5253.3 5371.7 5460.1 5464.5 5528.0 5307.1 5472.1 5502.1 5587.3 5596.7 22:59:01 INFO - PROCESS | 30201 | 22:59:01 INFO - PROCESS | 30201 | [#4] prototype.html Cycles:180 Average:4675.06 Median:5304.52 stddev:1349.16 (25.4%) stddev-sans-first:1312.85 22:59:01 INFO - PROCESS | 30201 | Values: 325.5 331.6 341.3 341.7 344.0 5279.6 5340.5 5343.0 5350.5 5431.5 5063.2 5120.8 5249.8 5265.2 5290.6 4124.4 4166.9 4169.8 4193.5 4197.8 4191.9 4195.5 4206.8 4215.3 4222.5 3977.5 4000.8 4139.3 4156.8 4161.1 4114.7 4132.1 4133.9 4140.4 4147.9 3691.8 3718.8 3724.5 3727.9 3753.9 5052.2 5086.9 5140.3 5169.9 5210.0 4730.4 4737.4 4766.8 4798.1 4798.8 5458.9 5470.5 5481.2 5484.5 5497.8 3951.5 4036.4 4054.4 4063.2 4065.7 5672.5 5697.8 5703.3 5740.5 5741.2 4141.5 4154.4 4189.5 4189.7 4195.9 4113.4 4185.3 4195.3 4245.0 4245.5 4685.6 4709.1 4729.0 4732.8 4752.7 5498.7 5517.0 5522.1 5534.2 5540.9 1382.2 1402.6 1434.5 1444.2 1464.7 987.7 1002.2 1019.3 1030.2 1033.6 5278.6 5292.1 5300.8 5308.2 5311.6 5574.3 5587.8 5606.6 5613.3 5626.8 5124.6 5267.6 5290.5 5320.5 5345.3 3000.0 3041.3 3043.3 3049.9 3050.3 5724.3 5836.7 5852.4 5853.5 5861.0 5626.7 5715.3 5742.6 5750.1 5752.6 5712.7 5736.5 5739.3 5742.8 5794.8 5382.6 5538.3 5554.2 5599.1 5616.2 5463.9 5512.6 5547.6 5548.1 5566.1 5779.6 5791.6 5794.7 5798.2 5828.3 3834.6 4079.2 4083.8 4092.9 4097.5 5704.2 5704.4 5707.2 5766.8 5792.4 5433.2 5435.0 5571.3 5611.8 5655.5 5521.9 5563.3 5567.7 5591.4 5620.4 5319.4 5458.6 5521.0 5537.0 5539.4 5614.8 5633.9 5642.2 5644.6 5690.7 5468.0 5554.1 5569.2 5583.3 5590.6 22:59:01 INFO - PROCESS | 30201 | 22:59:01 INFO - PROCESS | 30201 | [#5] yui.html Cycles:180 Average:15587.73 Median:48968.39 stddev:18747.79 (38.3%) stddev-sans-first:18767.64 22:59:01 INFO - PROCESS | 30201 | Values: 832.5 839.1 839.1 846.2 848.2 14681.1 15184.4 15436.2 15458.1 15483.1 13318.7 14164.6 14205.7 14263.2 14295.1 675.1 795.7 808.1 816.0 827.4 815.7 816.7 817.6 817.7 820.9 855.7 858.6 861.3 862.5 863.5 707.0 838.4 857.4 858.2 864.4 625.0 626.0 626.8 627.7 627.8 14328.7 14333.7 14357.0 14369.3 14406.3 13111.1 13489.1 13619.5 13703.8 13751.3 12006.6 12019.9 12025.7 12044.3 12279.1 4390.8 4434.6 4472.8 4495.8 4542.3 51865.5 53112.0 53241.2 53434.5 54071.0 10145.2 10167.4 10317.6 10432.3 10618.9 20344.7 20363.9 20380.7 20404.6 20425.2 8188.2 8195.1 8248.4 8386.7 8474.7 4482.5 4579.3 4663.3 4744.2 4762.9 3365.7 3484.5 3485.5 3493.2 3499.2 654.6 677.3 708.4 711.5 725.0 631.9 687.8 752.2 792.6 793.1 9517.2 9594.8 9896.4 9904.7 9916.2 772.8 775.4 787.7 790.0 800.1 4632.6 4762.0 4781.5 4785.2 4810.6 71787.0 73485.6 73588.0 74104.3 74516.6 57600.0 59362.0 59955.0 60186.4 60688.7 53937.5 54674.7 54910.7 55182.2 55209.7 8871.5 9018.0 9047.6 9083.4 9139.3 36266.0 36353.6 36898.4 37021.4 37474.0 45142.8 48461.3 49475.5 49790.5 50785.7 11055.2 11158.2 11936.2 12018.5 12056.0 13440.6 13459.7 13586.3 13594.3 13664.8 12696.3 12782.5 12921.2 13322.7 13339.7 12354.3 12427.3 12460.8 12663.2 13304.9 12101.7 12317.7 12661.6 12702.0 13057.8 9101.7 9154.6 9200.2 9341.9 9648.3 9869.0 10575.6 10725.8 10775.2 10809.9 22:59:01 INFO - PROCESS | 30201 | -------- Summary: end -------- 22:59:01 INFO - PROCESS | 30201 | 22:59:02 INFO - TEST-INFO | 30201: exit 0 22:59:02 INFO - TEST-OK | dromaeo_css | took 1113672ms 22:59:02 INFO - TEST-START | kraken 22:59:02 INFO - operating with platform_type : linux_ 22:59:02 INFO - Initialising browser for kraken test... 22:59:02 INFO - TEST-INFO | started process 30378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6QgzM1/profile http://localhost:59264/getInfo.html) 22:59:06 INFO - PROCESS | 30378 | __metrics Screen width/height:1600/1200 22:59:06 INFO - PROCESS | 30378 | colorDepth:24 22:59:06 INFO - PROCESS | 30378 | Browser inner width/height: 1024/697 22:59:06 INFO - PROCESS | 30378 | __metrics 22:59:07 ERROR - PROCESS | 30378 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:59:08 INFO - TEST-INFO | 30378: exit 0 22:59:08 INFO - Browser initialized. 22:59:08 INFO - Running cycle 1/1 for kraken test... 22:59:08 INFO - TEST-INFO | started process 30458 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6QgzM1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:59:20 INFO - PROCESS | 30458 | RSS: Main: 152997888 22:59:20 INFO - PROCESS | 30458 | 22:59:23 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59264/tests/kraken/driver.html?testName=audio-beat-detection) 22:59:23 INFO - PROCESS | 30458 | RSS: Main: 209682432 22:59:23 INFO - PROCESS | 30458 | 22:59:26 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59264/tests/kraken/driver.html?testName=audio-dft) 22:59:27 INFO - PROCESS | 30458 | RSS: Main: 197709824 22:59:27 INFO - PROCESS | 30458 | 22:59:30 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59264/tests/kraken/driver.html?testName=audio-fft) 22:59:31 INFO - PROCESS | 30458 | RSS: Main: 265945088 22:59:31 INFO - PROCESS | 30458 | 22:59:33 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59264/tests/kraken/driver.html?testName=audio-oscillator) 22:59:33 INFO - PROCESS | 30458 | RSS: Main: 176349184 22:59:33 INFO - PROCESS | 30458 | 22:59:36 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59264/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:59:36 INFO - PROCESS | 30458 | RSS: Main: 179990528 22:59:36 INFO - PROCESS | 30458 | 22:59:39 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59264/tests/kraken/driver.html?testName=imaging-darkroom) 22:59:40 INFO - PROCESS | 30458 | RSS: Main: 215187456 22:59:40 INFO - PROCESS | 30458 | 22:59:44 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59264/tests/kraken/driver.html?testName=imaging-desaturate) 22:59:44 INFO - PROCESS | 30458 | RSS: Main: 213291008 22:59:44 INFO - PROCESS | 30458 | 22:59:47 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59264/tests/kraken/driver.html?testName=json-parse-financial) 22:59:48 INFO - PROCESS | 30458 | RSS: Main: 214618112 22:59:48 INFO - PROCESS | 30458 | 22:59:49 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59264/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:59:50 INFO - PROCESS | 30458 | RSS: Main: 204443648 22:59:50 INFO - PROCESS | 30458 | 22:59:52 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59264/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:59:52 INFO - PROCESS | 30458 | RSS: Main: 207118336 22:59:52 INFO - PROCESS | 30458 | 22:59:55 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59264/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:59:55 INFO - PROCESS | 30458 | RSS: Main: 218177536 22:59:55 INFO - PROCESS | 30458 | 22:59:58 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:59:59 INFO - PROCESS | 30458 | RSS: Main: 224456704 22:59:59 INFO - PROCESS | 30458 | 23:00:01 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:00:02 INFO - PROCESS | 30458 | RSS: Main: 221421568 23:00:02 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | Cycle 1(1): loaded http://localhost:59264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59264/tests/kraken/driver.html?testName=ai-astar) 23:00:04 INFO - PROCESS | 30458 | RSS: Main: 267812864 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | __start_tp_report 23:00:04 INFO - PROCESS | 30458 | _x_x_mozilla_page_load 23:00:04 INFO - PROCESS | 30458 | _x_x_mozilla_page_load_details 23:00:04 INFO - PROCESS | 30458 | |i|pagename|runs| 23:00:04 INFO - PROCESS | 30458 | |0;ai-astar;111;105;110;110;109;109;110;108;109;110 23:00:04 INFO - PROCESS | 30458 | |1;audio-beat-detection;152;125;126;215;125;124;155;126;157;125 23:00:04 INFO - PROCESS | 30458 | |2;audio-dft;161;160;161;160;160;162;160;160;161;162 23:00:04 INFO - PROCESS | 30458 | |3;audio-fft;74;74;73;143;77;76;73;76;122;75 23:00:04 INFO - PROCESS | 30458 | |4;audio-oscillator;92;92;161;91;92;91;136;92;91;142 23:00:04 INFO - PROCESS | 30458 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;116;117;117 23:00:04 INFO - PROCESS | 30458 | |6;imaging-darkroom;146;146;146;146;145;145;146;145;145;145 23:00:04 INFO - PROCESS | 30458 | |7;imaging-desaturate;95;95;95;96;96;95;95;96;95;95 23:00:04 INFO - PROCESS | 30458 | |8;json-parse-financial;54;53;53;53;53;55;53;106;51;52 23:00:04 INFO - PROCESS | 30458 | |9;json-stringify-tinderbox;62;62;62;62;62;62;62;62;63;63 23:00:04 INFO - PROCESS | 30458 | |10;stanford-crypto-aes;85;78;78;78;83;79;83;80;79;79 23:00:04 INFO - PROCESS | 30458 | |11;stanford-crypto-ccm;130;122;129;117;119;119;122;117;189;123 23:00:04 INFO - PROCESS | 30458 | |12;stanford-crypto-pbkdf2;162;161;158;158;160;157;161;160;160;160 23:00:04 INFO - PROCESS | 30458 | |13;stanford-crypto-sha256-iterative;63;65;63;65;63;64;64;63;61;63 23:00:04 INFO - PROCESS | 30458 | __end_tp_report 23:00:04 INFO - PROCESS | 30458 | __start_cc_report 23:00:04 INFO - PROCESS | 30458 | _x_x_mozilla_cycle_collect,1874 23:00:04 INFO - PROCESS | 30458 | __end_cc_report 23:00:04 INFO - PROCESS | 30458 | __startTimestamp1463724004344__endTimestamp 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | ------- Summary: start ------- 23:00:04 INFO - PROCESS | 30458 | Number of tests: 14 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.00 stddev:1.66 (1.5%) stddev-sans-first:1.62 23:00:04 INFO - PROCESS | 30458 | Values: 111.0 105.0 110.0 110.0 109.0 109.0 110.0 108.0 109.0 110.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#1] audio-beat-detection Cycles:10 Average:143.00 Median:139.00 stddev:28.90 (20.8%) stddev-sans-first:30.47 23:00:04 INFO - PROCESS | 30458 | Values: 152.0 125.0 126.0 215.0 125.0 124.0 155.0 126.0 157.0 125.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 23:00:04 INFO - PROCESS | 30458 | Values: 161.0 160.0 161.0 160.0 160.0 162.0 160.0 160.0 161.0 162.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#3] audio-fft Cycles:10 Average:86.30 Median:74.50 stddev:24.88 (33.4%) stddev-sans-first:25.99 23:00:04 INFO - PROCESS | 30458 | Values: 74.0 74.0 73.0 143.0 77.0 76.0 73.0 76.0 122.0 75.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#4] audio-oscillator Cycles:10 Average:108.00 Median:91.50 stddev:27.16 (29.7%) stddev-sans-first:28.19 23:00:04 INFO - PROCESS | 30458 | Values: 92.0 92.0 161.0 91.0 92.0 91.0 136.0 92.0 91.0 142.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 23:00:04 INFO - PROCESS | 30458 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 23:00:04 INFO - PROCESS | 30458 | Values: 146.0 146.0 146.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 23:00:04 INFO - PROCESS | 30458 | Values: 95.0 95.0 95.0 96.0 96.0 95.0 95.0 96.0 95.0 95.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:53.00 stddev:16.79 (31.7%) stddev-sans-first:17.74 23:00:04 INFO - PROCESS | 30458 | Values: 54.0 53.0 53.0 53.0 53.0 55.0 53.0 106.0 51.0 52.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 23:00:04 INFO - PROCESS | 30458 | Values: 62.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 63.0 63.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:79.50 stddev:2.53 (3.2%) stddev-sans-first:2.00 23:00:04 INFO - PROCESS | 30458 | Values: 85.0 78.0 78.0 78.0 83.0 79.0 83.0 80.0 79.0 79.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:122.50 stddev:21.66 (17.7%) stddev-sans-first:22.97 23:00:04 INFO - PROCESS | 30458 | Values: 130.0 122.0 129.0 117.0 119.0 119.0 122.0 117.0 189.0 123.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.70 Median:160.00 stddev:1.57 (1.0%) stddev-sans-first:1.42 23:00:04 INFO - PROCESS | 30458 | Values: 162.0 161.0 158.0 158.0 160.0 157.0 161.0 160.0 160.0 160.0 23:00:04 INFO - PROCESS | 30458 | 23:00:04 INFO - PROCESS | 30458 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.50 stddev:1.17 (1.8%) stddev-sans-first:1.24 23:00:04 INFO - PROCESS | 30458 | Values: 63.0 65.0 63.0 65.0 63.0 64.0 64.0 63.0 61.0 63.0 23:00:04 INFO - PROCESS | 30458 | -------- Summary: end -------- 23:00:04 INFO - PROCESS | 30458 | 23:00:05 INFO - TEST-INFO | 30458: exit 0 23:00:05 INFO - TEST-OK | kraken | took 63060ms 23:00:05 INFO - SUITE-END | took 1176s 23:00:05 INFO - Completed test suite (00:19:36) 23:00:05 INFO - javascript benchmark 23:00:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3127.32762456072, 3145.370925814836, 3166.1134882232977, 3191.138392633987, 3192.281736609263, 5187.066328060957, 5257.23770053342, 5365.7585408656605, 5461.371942226645, 5467.945320546798, 5354.22363757255, 5420.078586640271, 5454.61817672763, 5526.364468086172, 5544.91682624761, 4869.342638743778, 4963.404391473028, 4966.379202599675, 4973.104841128596, 5105.3873535175835, 4994.650374473788, 5012.749362531877, 5034.320366750479, 5056.974715126419, 5069.543741063304, 4752.215884379073, 4832.685875418087, 4864.927026094611, 4948.1093403187415, 4955.578775804061, 4956.231784073474, 4971.552560269557, 4994.275830004629, 5024.472430394792, 5046.142155833517, 4805.927911081337, 4985.775640096168, 4991.925121123186, 5011.2483127530795, 5039.84880453587, 5253.973730131325, 5311.070562651521, 5323.228131920889, 5384.057789886754, 5385.165299378564, 5758.481736643722, 5791.102379131242, 5809.651420914759, 5816.534677225812, 5816.738246778863, 5680.147977803321, 5780.046292361755, 5856.882862342772, 5871.500922421599, 5872.941270587325, 5465.344158700942, 5550.555955523549, 5551.805686800943, 5555.972220138873, 5561.054620714448, 6050.42520960508, 6055.8486037848525, 6061.969690151521, 6062.605930614496, 6067.484263837623, 5459.317585301837, 5689.459501347365, 5712.514436272881, 5816.592838501264, 5829.387914268941, 5854.677992710356, 5916.467517923407, 5921.111833225051, 5925.762969481259, 5936.436038576328, 5807.419258074243, 5844.1818145459665, 5846.941530584725, 5854.531637468992, 5855.677937713382, 5499.092649712753, 5639.464250896158, 5664.405237450091, 5688.687122208318, 5824.650520968756, 4357.063231405263, 4360.280553708602, 4363.7381757094045, 4372.584604462571, 4396.846110386121, 3896.0649444133205, 3902.31709450845, 3912.6869850412468, 3942.6648734857285, 3956.6834653227675, 5337.306150200449, 5443.755031023614, 5450.890982180414, 5458.153986132156, 5501.504864562208, 5843.620164689281, 5844.649321040752, 5855.238818953509, 5859.853503662443, 5888.5289176865745, 5496.532794712494, 5512.972435137799, 5527.419620939824, 5528.530074943595, 5528.75120619573, 4812.711237325771, 4931.161702510582, 4986.301917731521, 4993.575546078624, 5004.449510553836, 5962.314333851663, 6006.909896351558, 6014.849628759316, 6017.247844019497, 6018.368071352533, 5614.69119198448, 5819.970900145472, 5823.068309070443, 5843.29880414353, 5843.853903652443, 5879.853003674943, 5924.229850119629, 5937.19847820556, 5974.1934838796205, 5992.221011268507, 5657.264555607745, 5687.544996400361, 5691.914621280767, 5700.116481945305, 5706.42935706426, 5579.302587176603, 5719.914201286901, 5816.2438882946635, 5828.184054232495, 5831.591788574759, 5607.355154157324, 5669.603127781016, 5671.631343962629, 5672.347680016851, 5725.742341594721, 5611.915821262603, 5637.633553818989, 5651.943480565306, 5652.802151924663, 5664.943350566442, 5445.673259604437, 5572.387037425961, 5577.247071645276, 5577.972110139423, 5593.860153496195, 5790.073588226032, 5835.4748072673665, 5836.941630583639, 5840.678762668094, 5842.883142337045, 5640.407757185437, 5681.573881958787, 5685.118806584986, 5688.943110569007, 5729.312482502127], "value": 5354.512388092698, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.249993757647, 630.8801327747728, 634.7238951056288, 644.4425571880323, 662.2251965200714, 33746.0, 36462.63537364629, 36530.63469365309, 43729.56270437295, 44216.55783442165, 45442.772786136105, 46190.53809461913, 46436.76781616096, 46524.302135467995, 46642.7667861661, 8697.608607612641, 8884.067172946845, 8955.955220223923, 8969.372143949926, 8971.506567138806, 8813.823723525526, 8951.686690965818, 8965.23795477382, 8966.0, 8975.596098175598, 8983.10168983103, 9054.728358149234, 9061.682841100563, 9064.093590640916, 9071.138241867046, 8887.555622218895, 8959.0, 8966.417182883157, 8969.237614802749, 8970.551472426385, 8821.426607270507, 8902.421342612774, 8910.50992195429, 8911.910880891237, 8915.777105572413, 42910.57089429097, 43441.0, 43454.56545434537, 43540.78229608863, 43732.3440148398, 3581.2658405026905, 3629.546306711661, 3710.0, 3788.981055094707, 4153.273177193979, 31373.0, 33198.6680133198, 33715.0, 33774.14242216677, 33927.0, 4310.52584215736, 4371.103923695635, 4384.473863136413, 4483.349914262446, 4491.955080449186, 352808.0, 362309.0, 363465.0, 364159.0, 366096.9999999973, 63019.36980630135, 63163.6841815788, 63836.68081659562, 63936.92599203068, 64011.67994159999, 66856.0, 67641.66179169172, 67904.0, 67938.30154246085, 68047.65976170088, 17147.828521714873, 17313.7402938956, 17322.566935826704, 17325.56686082858, 17340.393086241922, 12437.813432798515, 12533.561325353568, 12537.0, 12551.937240313922, 12560.497580096693, 17771.696074900974, 20324.390268291976, 20382.490437739176, 20649.17403303881, 20668.793312066988, 6284.622922624566, 7161.89257161143, 7463.664135113824, 7494.21310762373, 7658.349040331523, 975.7219192530094, 990.4601991914424, 1048.7168464514539, 1065.3661071662348, 1125.7861006408757, 31828.204294892814, 33068.3386332272, 33169.83415082957, 33349.33301334008, 33869.66130338665, 1133.7165708572857, 1134.9092072634169, 1184.5557915781583, 1193.1349771415655, 1214.0409076829383, 17154.39959601408, 17429.302827886997, 17430.302787888595, 17511.211995460235, 17704.468865934043, 533350.0, 542424.0, 549234.5076549263, 549872.0, 860969.0, 59881.0, 60280.39719602835, 60508.394916051155, 60641.69679151576, 60661.69669151626, 1273.2360583649736, 1335.5726167626267, 1383.391307824554, 1396.9930150349182, 1408.5422237772764, 29707.405851883275, 33584.49623255604, 33620.49569256512, 33635.0, 33986.490202646484, 51332.48667513426, 51692.74153629358, 52195.217071743195, 52201.21698174607, 52277.7386113067, 429777.0, 433710.0, 434406.0, 435235.0, 436103.0, 26260.212193634223, 28261.0, 28624.284392889513, 29272.70727292785, 29340.85329573424, 48169.759151204016, 48441.27338090002, 48738.26892596685, 49866.50133498764, 49912.75043624759, 40129.39805902972, 40578.18843623052, 41342.0, 41566.0, 41733.37399938943, 41029.589704102575, 41838.37242441305, 42113.0, 42194.03798373357, 42432.78783606062, 40756.1848763029, 40987.59012409838, 41176.1764764709, 42124.368134477394, 42432.78783606062, 36203.45694814527, 37017.259654806214, 37062.25875482529, 37526.249475010896, 37702.245955080194, 28458.715412846435, 28466.146015619564, 28492.57261141043, 28712.282192945342, 28882.71117288884], "value": 20631.04083002975, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.6385661334957, 397.83887525552154, 404.8380647740905, 408.43227913200656, 413.84593192433385, 14079.0, 17854.82145178548, 18066.187021584028, 19620.0, 19631.31290404836, 15017.62455938602, 17887.910560447213, 18516.259349626034, 18905.527361815963, 19258.51853703658, 5465.808696695606, 5678.659280443166, 5703.5152012079025, 5716.771329146829, 5718.285214348207, 4024.396340548926, 5582.748776305056, 5664.688442135681, 6003.8198854034445, 6272.3100458949475, 6334.176557047577, 6351.301356850743, 6354.237491501003, 6355.523335749815, 6360.395762402564, 6143.0, 6276.68616569172, 6345.49236061114, 6352.5553211275, 6366.045093236005, 5560.499555040065, 5561.304836895388, 5562.916556251659, 5576.804811831566, 6475.352464753534, 25253.62119568208, 25678.0, 25862.6120608191, 26483.73516264851, 26560.73439265602, 2456.336789066942, 2614.5947378156416, 2625.488029834183, 2645.2593273883344, 3191.4414977378865, 15272.770908436383, 15569.610759730984, 15610.141442220673, 16070.839291607052, 16610.169491525376, 2112.6197284488785, 2115.883626400547, 2163.621366260898, 2204.239537359608, 2232.2745107839855, 59244.70377648127, 59929.40070599282, 60241.6987915062, 60667.39332606706, 60791.69604151995, 17679.381221657437, 17866.30270124336, 18034.639307213783, 18252.083531229295, 18366.449006529827, 33083.33833323368, 33123.33753324968, 33417.83291083529, 33464.1633959153, 33470.0, 9776.755581110387, 10114.645987390553, 10215.426340743506, 10249.641262555775, 10393.792124157475, 8578.056413794478, 8645.524496152771, 8688.78278043054, 8740.562971851383, 8789.165029322205, 7391.445641576904, 8415.621297041647, 9055.81888362229, 9117.270618350516, 9310.767230819283, 3187.6971687689634, 3211.2563616405364, 3325.93348133036, 3488.389531831919, 3550.797657411388, 960.6061514779046, 1002.0230275481348, 1012.7721262715977, 1027.0448482910965, 1105.7415030924926, 19088.904555477133, 19581.0, 19950.70073948892, 20085.0, 20258.0, 1021.1014307409581, 1027.2244455436098, 1028.9691309260734, 1040.6825918095053, 1044.2116202267391, 10585.735356616146, 11122.666320010412, 11161.776764464828, 11250.268732532359, 11257.099432045417, 454366.0, 455679.0, 457400.0, 457827.0, 458424.0, 85409.0, 86057.0, 87760.0, 88165.0, 89201.55399223093, 990.4156547636928, 1022.7136401807505, 1028.3161697471248, 1049.3441599000626, 1053.2416660004785, 17222.0, 17245.89253666229, 17420.128993550527, 17604.0, 17769.111544422987, 32770.01689949305, 37152.256954861296, 37380.439293410076, 41116.794416027726, 41842.790786047095, 51769.22346164735, 52478.737606311726, 54147.729261353445, 57369.713151435644, 57433.42566574448, 7739.922600774145, 8065.1934806518875, 8738.606762695821, 8796.91203087961, 9039.59321830506, 13540.255285959367, 13929.721405572034, 14000.409310312156, 14213.50252741149, 14316.0, 12163.939180304042, 12164.939175304067, 12199.939000305296, 12593.557590857996, 12629.93685031569, 12375.257484550955, 12433.191842530568, 12456.564020259248, 12462.127651064338, 12734.363281836057, 12297.631071067883, 12804.335436393209, 12823.166494178222, 12982.221066736027, 13105.934470327587, 8063.83872322562, 8170.959145204236, 8201.71294004707, 8229.629666664894, 8381.916180838114, 12110.515579376726, 12126.237549576896, 12215.69460763488, 12744.10791244604, 12817.0], "value": 10148.43654966565, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.5669157523807, 1458.445790599572, 1500.3323521033137, 1600.7518834580637, 1781.3676144968545, 8233.464824786384, 8622.18089281518, 8622.482651040935, 8793.95603021984, 8815.955920220405, 8557.614907329169, 8723.825523489526, 8753.95623021884, 8758.211760941515, 8759.693410730626, 2031.4108908416542, 2212.6459766437374, 2277.601419751545, 2323.9883800580974, 2351.3651314145172, 2295.5982703026903, 2301.045066297493, 2301.171578231835, 2304.0553373117027, 2304.585174668559, 2318.6058370077126, 2321.1527792355755, 2329.6738456616085, 2346.7066616672914, 2351.318117745852, 1964.3419454482694, 2159.427751645815, 2183.159483598818, 2217.02450921594, 2219.5117074243803, 2250.3024062540667, 2258.8870556472275, 2275.9317220483413, 2276.2260831317267, 2276.965845512302, 8761.21149096578, 8804.603792829274, 8809.471431714117, 8812.823743525094, 8825.558722063872, 5143.614228932845, 5189.974050129763, 5196.402413722432, 5207.60942929278, 5207.973960130213, 3168.0, 3201.951970720441, 3254.5931758530182, 3286.96713032869, 3297.472404415282, 1011.145581983223, 1011.989880101197, 1027.7944411117805, 1031.1235449867627, 1034.0745033195249, 11937.462814173383, 11950.402479876058, 12003.699907502383, 12009.339486328248, 12009.93995030028, 4478.283474644041, 4528.637708983273, 4564.292534657134, 4581.221192397301, 4646.86059418218, 10002.349847259904, 10005.79988400228, 10016.449095299682, 10047.899521004843, 10062.0, 5124.974375128175, 5190.92213616796, 5193.662411943212, 5193.662411943287, 5199.532042116152, 4266.807993640294, 4590.839320623762, 4721.268203428474, 4726.196546587088, 4767.0, 3882.301185786529, 3951.269015232191, 4019.91960160801, 4054.6958978076764, 4058.7158898877387, 1211.787937111002, 1212.8847759462838, 1228.5147366790068, 1231.7290196156832, 1239.913206075566, 892.6340200517886, 952.6094301336558, 1012.7923775626126, 1039.4750650921237, 1053.1101219469535, 6001.699915004232, 6052.878942421127, 6194.0, 6256.061590761422, 6269.592476489013, 958.6692591056075, 962.6678795815433, 976.4108246071345, 992.1665800727424, 992.1814503034881, 4224.239636865394, 4284.957150428519, 4285.871423857289, 4295.37717031035, 4330.480342358937, 10687.30532515384, 10918.727031824268, 11009.6146634869, 11038.779224415466, 11045.83431248532, 10852.077573406192, 10941.890581094247, 10951.178661600412, 10952.616658416917, 10954.397508137128, 7765.378769698411, 8082.757517274491, 8194.262516373556, 8220.054693710299, 8247.0, 5989.401059893976, 6035.245594300713, 6095.664738439428, 6147.0779383092895, 6348.5238607105575, 9840.458774767456, 9858.901410985798, 9981.0, 10039.498025098861, 10078.294519383573, 9979.301448898797, 10168.49157542105, 10212.0, 10286.434246116536, 10293.691189264335, 7200.783976480714, 7629.54222746637, 7830.686772529035, 7859.607019649109, 8016.0, 8728.476291422536, 8740.257078148428, 8777.166169213915, 8809.515476648847, 8824.823503530022, 7547.547147171187, 7669.271419215165, 7830.1386847447875, 7876.527408355517, 8003.559804210824, 7624.466287359832, 7647.005889234364, 7712.614369281626, 7826.452148349561, 7857.371410287049, 7120.394766444703, 7148.392386646985, 7186.065811444479, 7187.964060179875, 7206.56760594366, 5253.290805741329, 5371.650842695212, 5460.099083651312, 5464.480874316933, 5527.972360138174, 5307.097793375058, 5472.069748142746, 5502.119660854244, 5587.3015873015875, 5596.664200148004], "value": 4624.534205035773, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.50523204728796, 331.57227176941745, 341.28671077448143, 341.6975976261009, 343.9896803095905, 5279.647423885831, 5340.519353258211, 5342.973285133569, 5350.5184533391985, 5431.511163995245, 5063.215201643742, 5120.820771273006, 5249.790008399659, 5265.236540701596, 5290.629655924087, 4124.381342798574, 4166.854160104397, 4169.812358443862, 4193.517745459266, 4197.790110494486, 4191.853285135021, 4195.4545909031785, 4206.810693518783, 4215.346621273692, 4222.493300803908, 3977.4630424892703, 4000.799960002003, 4139.275626765319, 4156.750594964296, 4161.105362347094, 4114.670826333886, 4132.132252227036, 4133.9173216535655, 4140.358244472111, 4147.937780933288, 3691.7600355977042, 3718.8140592970512, 3724.478572999782, 3727.9068023299633, 3753.887383378503, 5052.242163675442, 5086.923696144524, 5140.280360749498, 5169.948300516984, 5210.036143313461, 4730.408698912636, 4737.407824021997, 4766.808297925519, 4798.0883632110135, 4798.832040878552, 5458.890822183574, 5470.480304371078, 5481.177823326493, 5484.533814625762, 5497.752601132958, 3951.4665520154986, 4036.354183330653, 4054.3918412238104, 4063.167050754588, 4065.695072869517, 5672.5178359839465, 5697.800576979786, 5703.3441154267375, 5740.54075673945, 5741.196232527408, 4141.461609990682, 4154.376843473503, 4189.49726032878, 4189.748615083044, 4195.895102622459, 4113.4035564843525, 4185.288500954845, 4195.286801243796, 4245.0, 4245.469316335458, 4685.554872287127, 4709.105269998689, 4728.95962888164, 4732.834350797774, 4752.738599377068, 5498.725063746797, 5517.0, 5522.088855338824, 5534.1975413565615, 5540.889182216333, 1382.2121390807345, 1402.6007378469894, 1434.483585909072, 1444.241773069147, 1464.6704491489543, 987.6888780034266, 1002.173207104142, 1019.261035749076, 1030.2427715629008, 1033.6278939581744, 5278.551323137576, 5292.126799078109, 5300.826661068943, 5308.203769434615, 5311.601629877775, 5574.2998385492, 5587.8486782589, 5606.579506537026, 5613.326400831927, 5626.831195064155, 5124.590032797368, 5267.604929630293, 5290.5238528531845, 5320.547753441001, 5345.305110335709, 3000.0399872041176, 3041.330907200412, 3043.269615292314, 3049.87800487978, 3050.3289276359164, 5724.341700704473, 5836.708164591753, 5852.4147585241135, 5853.541512564733, 5860.970695146497, 5626.746796394172, 5715.314162300468, 5742.569307301969, 5750.051241545182, 5752.568557358131, 5712.657525481509, 5736.483716465538, 5739.282589676374, 5742.827715168552, 5794.82615521535, 5382.623216374816, 5538.335399752055, 5554.194641776839, 5599.0683214291, 5616.241807356118, 5463.907218556385, 5512.586556008235, 5547.556195504269, 5548.140038294071, 5566.137248726453, 5779.595428319977, 5791.581062639681, 5794.739236734441, 5798.159266906359, 5828.329742079715, 3834.616538346255, 4079.1637714269095, 4083.7549747015273, 4092.9386059209733, 4097.528784189856, 5704.2014118024335, 5704.429557044263, 5707.1724599933605, 5766.817802350424, 5792.420757924174, 5433.185022246695, 5435.0, 5571.272905399339, 5611.803586874603, 5655.4910058094965, 5521.889562208814, 5563.304586926634, 5567.721613919207, 5591.384947655836, 5620.353659329229, 5319.366913377903, 5458.645188062763, 5520.957245819072, 5536.972315138399, 5539.424167761849, 5614.831555053355, 5633.8591535211945, 5642.210090587239, 5644.576656750843, 5690.687012214368, 5467.972660136674, 5554.08357620988, 5569.248151499496, 5583.302087239095, 5590.608657394106], "value": 4249.981656236638, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [832.4880198677813, 839.08539691736, 839.1440730454932, 846.1580727176458, 848.160321281931, 14681.119132852024, 15184.39262429503, 15436.15101169434, 15458.07251564907, 15483.14842683652, 13318.733625327488, 14164.575062748108, 14205.71588568228, 14263.215523146222, 14295.070820396693, 675.1021141881287, 795.7493389582291, 808.1191501263606, 816.008549612221, 827.3505298340804, 815.7308088330836, 816.6692489541745, 817.6116344736262, 817.6851912013888, 820.8645573480399, 855.6748435594496, 858.6307887608326, 861.2937391339104, 862.5169904853271, 863.4646519158099, 706.9611171385546, 838.4214891724752, 857.4469467193657, 858.2404571953804, 864.394923553515, 624.9843753906143, 626.0421555020826, 626.7806267806277, 627.7489004398232, 627.7802769030849, 14328.713425731532, 14333.713325733426, 14357.0, 14369.281535923163, 14406.279686015658, 13111.147775394664, 13489.111524386593, 13619.523316683868, 13703.794443083361, 13751.2659206673, 12006.579769708105, 12019.93990030053, 12025.699357516045, 12044.337561434118, 12279.079069069856, 4390.824367025315, 4434.578715022068, 4472.84345047925, 4495.842645507391, 4542.341360502742, 51865.48134518644, 53112.0, 53241.2013819793, 53434.46565534334, 54071.0, 10145.239107067, 10167.440790756431, 10317.587296508205, 10432.321899076536, 10618.893811061791, 20344.69482957757, 20363.898180509004, 20380.694289585666, 20404.591908161754, 20425.182992680424, 8188.222118898695, 8195.139510351446, 8248.422610417214, 8386.650957231564, 8474.74575762728, 4482.48888536826, 4579.335996280587, 4663.323818046364, 4744.1934871072, 4762.903064789871, 3365.74756893234, 3484.47732840076, 3485.4597537381746, 3493.2314890724, 3499.177693242086, 654.5679851298121, 677.3260605697362, 708.412018025038, 711.4699548836151, 724.9673764680497, 631.8767840271195, 687.814290141659, 752.2477522477523, 792.5786721565557, 793.1473665809278, 9517.191038761644, 9594.760130996641, 9896.406215627085, 9904.702858914245, 9916.206703463706, 772.7681695491331, 775.3642013548836, 787.7164220880478, 789.9526028438312, 800.0799081056831, 4632.559906808848, 4762.0, 4781.545753153515, 4785.210440277316, 4810.63920205986, 71787.0, 73485.6325718368, 73588.0, 74104.25895741081, 74516.62741686257, 57600.0, 59362.0, 59955.0, 60186.39813601896, 60688.69655651782, 53937.46062539403, 54674.72662636662, 54910.72544637331, 55182.17226741602, 55209.72395137999, 8871.467711937305, 9018.0, 9047.592858321523, 9083.409578377385, 9139.268858491438, 36266.0, 36353.63646363608, 36898.44652330271, 37021.44467832931, 37474.0, 45142.77428612836, 48461.27308090311, 49475.50524494853, 49790.50209497858, 50785.746071270885, 11055.22613417053, 11158.218924675519, 11936.164468487123, 12018.459169337226, 12056.0, 13440.596782096552, 13459.730805384033, 13586.252756098509, 13594.320283985564, 13664.795028074388, 12696.301703406401, 12782.48870045225, 12921.224726516439, 13322.733545328845, 13339.733205335644, 12354.25874447536, 12427.254364738144, 12460.813087803508, 12663.176893501894, 13304.933475332562, 12101.69745756363, 12317.692057698629, 12661.556845510362, 12702.0, 13057.804132938203, 9101.726948191565, 9154.588043537924, 9200.21798147165, 9341.906580934376, 9648.276379271723, 9869.0, 10575.62985295511, 10725.785484290427, 10775.191860610328, 10809.891901080888], "value": 6932.851350883107, "unit": "score"}], "name": "dromaeo_css", "value": 7311.543581135833, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 110.0, 110.0, 109.0, 109.0, 110.0, 108.0, 109.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 125.0, 126.0, 215.0, 125.0, 124.0, 155.0, 126.0, 157.0, 125.0], "value": 143.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 160.0, 162.0, 160.0, 160.0, 161.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 143.0, 77.0, 76.0, 73.0, 76.0, 122.0, 75.0], "value": 86.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 161.0, 91.0, 92.0, 91.0, 136.0, 92.0, 91.0, 142.0], "value": 108.0, "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, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 53.0, 53.0, 55.0, 53.0, 106.0, 51.0, 52.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 63.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 78.0, 78.0, 78.0, 83.0, 79.0, 83.0, 80.0, 79.0, 79.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 122.0, 129.0, 117.0, 119.0, 119.0, 122.0, 117.0, 189.0, 123.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 158.0, 158.0, 160.0, 157.0, 161.0, 160.0, 160.0, 160.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 63.0, 65.0, 63.0, 64.0, 64.0, 63.0, 61.0, 63.0], "value": 63.4, "unit": "score"}], "name": "kraken", "value": 1517.2, "alertThreshold": 2.0}]} 23:00:05 INFO - Return code: 0 23:00:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:00:05 ERROR - # TBPL SUCCESS # 23:00:05 INFO - Running post-action listener: _resource_record_post_action 23:00:05 INFO - ##### 23:00:05 INFO - ##### Finished run-tests step (success) 23:00:05 INFO - ##### 23:00:05 INFO - Running post-run listener: _resource_record_post_run 23:00:05 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 73728; Write bytes: 543776768; Read time: 20; Write time: 669948 23:00:05 INFO - TinderboxPrint: CPU usage
13.1% 23:00:05 INFO - TinderboxPrint: I/O read bytes / time
73,728 / 20 23:00:05 INFO - TinderboxPrint: I/O write bytes / time
543,776,768 / 669,948 23:00:05 INFO - TinderboxPrint: CPU idle
8,282.9 (86.8%) 23:00:05 INFO - TinderboxPrint: CPU user
1,206.2 (12.6%) 23:00:05 INFO - TinderboxPrint: Swap in / out
0 / 0 23:00:05 INFO - install - Wall time: 17s; CPU: 16.0%; Read bytes: 0; Write bytes: 254160896; Read time: 0; Write time: 384716 23:00:05 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 73728; Write bytes: 289615872; Read time: 20; Write time: 285232 23:00:06 INFO - Running post-run listener: _upload_blobber_files 23:00:06 INFO - Blob upload gear active. 23:00:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:00:06 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 23:00:06 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'] 23:00:06 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 23:00:06 INFO - (blobuploader) - INFO - Open directory for files ... 23:00:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:00:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:00:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:00:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:00:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:00:10 INFO - (blobuploader) - INFO - Done attempting. 23:00:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:00:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:00:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:00:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:00:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:00:11 INFO - (blobuploader) - INFO - Done attempting. 23:00:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:00:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:00:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:00:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:00:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:00:12 INFO - (blobuploader) - INFO - Done attempting. 23:00:12 INFO - (blobuploader) - INFO - Iteration through files over. 23:00:12 INFO - Return code: 0 23:00:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:00:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:00:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1ca5208aea75ad362fa44bacb7f7363b098ddf479ef4a22636b88464be1aa098c804b2e98cfac2a200bd441aa51f4002490c9df86a40fa5c0b2bdc01f63dc627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb868d27975f7adb478e48d8f77e31ba3d522996feb186c2777b6ad29725201bd5b17d0dfd991abff0a92eb63d80e94af8ea1e01c2e579678bbdbc618d84408d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f1ea333bad4acb962b11757a53478fe08ae6a840fab876300440902ec1cafb4cc088a14c2a7c3d04996c800510ad3d4cf3fd8127a1dfdcfe7bb73623e7114278"} 23:00:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:00:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:00:12 INFO - Contents: 23:00:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/1ca5208aea75ad362fa44bacb7f7363b098ddf479ef4a22636b88464be1aa098c804b2e98cfac2a200bd441aa51f4002490c9df86a40fa5c0b2bdc01f63dc627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb868d27975f7adb478e48d8f77e31ba3d522996feb186c2777b6ad29725201bd5b17d0dfd991abff0a92eb63d80e94af8ea1e01c2e579678bbdbc618d84408d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f1ea333bad4acb962b11757a53478fe08ae6a840fab876300440902ec1cafb4cc088a14c2a7c3d04996c800510ad3d4cf3fd8127a1dfdcfe7bb73623e7114278"} 23:00:12 INFO - Running post-run listener: copy_logs_to_upload_dir 23:00:12 INFO - Copying logs to upload dir... 23:00:12 INFO - mkdir: /builds/slave/test/build/upload/logs 23:00:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.630977 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-19 23:00:11.721750) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 23:00:11.724937) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927 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/1ca5208aea75ad362fa44bacb7f7363b098ddf479ef4a22636b88464be1aa098c804b2e98cfac2a200bd441aa51f4002490c9df86a40fa5c0b2bdc01f63dc627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb868d27975f7adb478e48d8f77e31ba3d522996feb186c2777b6ad29725201bd5b17d0dfd991abff0a92eb63d80e94af8ea1e01c2e579678bbdbc618d84408d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f1ea333bad4acb962b11757a53478fe08ae6a840fab876300440902ec1cafb4cc088a14c2a7c3d04996c800510ad3d4cf3fd8127a1dfdcfe7bb73623e7114278"} build_url:https://queue.taskcluster.net/v1/task/IZeO1LCATPKzM9LkGHGE0w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011356 build_url: 'https://queue.taskcluster.net/v1/task/IZeO1LCATPKzM9LkGHGE0w/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/1ca5208aea75ad362fa44bacb7f7363b098ddf479ef4a22636b88464be1aa098c804b2e98cfac2a200bd441aa51f4002490c9df86a40fa5c0b2bdc01f63dc627", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb868d27975f7adb478e48d8f77e31ba3d522996feb186c2777b6ad29725201bd5b17d0dfd991abff0a92eb63d80e94af8ea1e01c2e579678bbdbc618d84408d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f1ea333bad4acb962b11757a53478fe08ae6a840fab876300440902ec1cafb4cc088a14c2a7c3d04996c800510ad3d4cf3fd8127a1dfdcfe7bb73623e7114278"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 23:00:11.750801) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 23:00:11.751193) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1463719017.336718-207711927 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 23:00:11.836277) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 23:00:11.836677) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 23:00:11.837108) ========= ========= Total master_lag: 0.16 =========