builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1470118607.45 results: success (0) buildid: 20160801203011 builduid: e32d4b7331e947bc97700afc73ea0433 revision: af62b45afe58e1e92e90b9df9c7c3f66f8bac60e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:47.450978) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:47.451356) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:47.451804) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:47.470609) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:47.470870) ========= 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=14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 23:16:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-08-01 23:16:47 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102560 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:47.589692) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:47.590386) ========= 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=14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014683 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:47.639946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-01 23:16:47.640314) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev af62b45afe58e1e92e90b9df9c7c3f66f8bac60e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev af62b45afe58e1e92e90b9df9c7c3f66f8bac60e --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=14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 23:16:47,779 truncating revision to first 12 chars 2016-08-01 23:16:47,779 Setting DEBUG logging. 2016-08-01 23:16:47,779 attempt 1/10 2016-08-01 23:16:47,779 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/af62b45afe58?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 23:16:48,653 unpacking tar archive at: fx-team-af62b45afe58/testing/mozharness/ program finished with exit code 0 elapsedTime=1.210419 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-01 23:16:48.867436) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:48.867781) ========= script_repo_revision: af62b45afe58e1e92e90b9df9c7c3f66f8bac60e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:48.868272) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:48.868597) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 23:16:48.879361) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-08-01 23:16:48.879675) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:16:49 INFO - MultiFileLogger online at 20160801 23:16:49 in /builds/slave/test 23:16:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:16:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:16:49 INFO - {'append_to_log': False, 23:16:49 INFO - 'base_work_dir': '/builds/slave/test', 23:16:49 INFO - 'blob_upload_branch': 'Fx-Team', 23:16:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:16:49 INFO - 'branch': 'Fx-Team', 23:16:49 INFO - 'buildbot_json_path': 'buildprops.json', 23:16:49 INFO - 'config_files': ('talos/linux_config.py',), 23:16:49 INFO - 'default_actions': ('clobber', 23:16:49 INFO - 'read-buildbot-config', 23:16:49 INFO - 'download-and-extract', 23:16:49 INFO - 'populate-webroot', 23:16:49 INFO - 'create-virtualenv', 23:16:49 INFO - 'install', 23:16:49 INFO - 'run-tests'), 23:16:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:16:49 INFO - 'download_minidump_stackwalk': True, 23:16:49 INFO - 'download_symbols': 'ondemand', 23:16:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:16:49 INFO - 'tooltool.py': '/tools/tooltool.py', 23:16:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:16:49 INFO - '/tools/misc-python/virtualenv.py')}, 23:16:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:16:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:16:49 INFO - 'installer_path': 'installer.exe', 23:16:49 INFO - 'log_level': 'info', 23:16:49 INFO - 'log_name': 'talos', 23:16:49 INFO - 'log_to_console': True, 23:16:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:16:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:16:49 INFO - 'opt_config_files': (), 23:16:49 INFO - 'pip_index': False, 23:16:49 INFO - 'sps_profile': False, 23:16:49 INFO - 'sps_profile_interval': 0, 23:16:49 INFO - 'suite': 'dromaeojs', 23:16:49 INFO - 'system_bits': '32', 23:16:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:16:49 INFO - 'title': 'talos-linux64-ix-036', 23:16:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:16:49 INFO - 'use_talos_json': True, 23:16:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:16:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:16:49 INFO - 'work_dir': 'build'} 23:16:49 INFO - [mozharness: 2016-08-02 06:16:49.112741Z] Running clobber step. 23:16:49 INFO - Running pre-action listener: _resource_record_pre_action 23:16:49 INFO - Running main action method: clobber 23:16:49 INFO - rmtree: /builds/slave/test/build 23:16:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:16:49 INFO - Running post-action listener: _resource_record_post_action 23:16:49 INFO - [mozharness: 2016-08-02 06:16:49.571009Z] Finished clobber step (success) 23:16:49 INFO - [mozharness: 2016-08-02 06:16:49.571109Z] Running read-buildbot-config step. 23:16:49 INFO - Running pre-action listener: _resource_record_pre_action 23:16:49 INFO - Running main action method: read_buildbot_config 23:16:49 INFO - Using buildbot properties: 23:16:49 INFO - { 23:16:49 INFO - "project": "", 23:16:49 INFO - "product": "firefox", 23:16:49 INFO - "script_repo_revision": "production", 23:16:49 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:16:49 INFO - "repository": "", 23:16:49 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:16:49 INFO - "buildid": "20160801203011", 23:16:49 INFO - "pgo_build": "True", 23:16:49 INFO - "basedir": "/builds/slave/test", 23:16:49 INFO - "buildnumber": 330, 23:16:49 INFO - "slavename": "talos-linux64-ix-036", 23:16:49 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:16:49 INFO - "platform": "ubuntu64_hw", 23:16:49 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:16:49 INFO - "branch": "fx-team", 23:16:49 INFO - "repo_path": "integration/fx-team", 23:16:49 INFO - "revision": "af62b45afe58e1e92e90b9df9c7c3f66f8bac60e", 23:16:49 INFO - "stage_platform": "linux64-pgo", 23:16:49 INFO - "builduid": "e32d4b7331e947bc97700afc73ea0433", 23:16:49 INFO - "slavebuilddir": "test" 23:16:49 INFO - } 23:16:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 23:16:49 INFO - Running post-action listener: _resource_record_post_action 23:16:49 INFO - [mozharness: 2016-08-02 06:16:49.573562Z] Finished read-buildbot-config step (success) 23:16:49 INFO - [mozharness: 2016-08-02 06:16:49.573651Z] Running download-and-extract step. 23:16:49 INFO - Running pre-action listener: _resource_record_pre_action 23:16:49 INFO - Running main action method: download_and_extract 23:16:49 INFO - mkdir: /builds/slave/test/build/tests 23:16: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')]} 23:16:49 INFO - https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:16:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:16:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:16:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:16:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:16:51 INFO - Downloaded 1361 bytes. 23:16:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 23:16:51 INFO - Using the following test package requirements: 23:16:51 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 23:16:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:16:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:16:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:16:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:16:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:16:51 INFO - u'jsshell-linux-x86_64.zip'], 23:16:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:16:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:16:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 23:16:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:16:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:16:51 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:16:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:16:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:16:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:16:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 23:16:51 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:16:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:51 INFO - https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:16:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:16:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:16:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:16:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:16:52 INFO - Downloaded 21809034 bytes. 23:16:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:16:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:16:53 INFO - Return code: 0 23:16:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:16:53 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')]} 23:16:53 INFO - https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:16:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:16:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:16:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 23:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:16:56 INFO - Downloaded 21809034 bytes. 23:16:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:16:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:16:57 INFO - Return code: 0 23:16: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')]} 23:16:57 INFO - https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:16:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 23:16:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 23:16:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 23:16:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:16:58 INFO - Downloaded 14413723 bytes. 23:16:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:16:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:16:59 INFO - Return code: 0 23:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:59 INFO - https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:16:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:16:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:16:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:16:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:17:00 INFO - Downloaded 59383092 bytes. 23:17:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:17:00 INFO - mkdir: /builds/slave/test/properties 23:17:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:17:00 INFO - Writing to file /builds/slave/test/properties/build_url 23:17:00 INFO - Contents: 23:17:00 INFO - build_url:https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 23:17:01 INFO - Running post-action listener: _resource_record_post_action 23:17:01 INFO - Running post-action listener: set_extra_try_arguments 23:17:01 INFO - [mozharness: 2016-08-02 06:17:01.047814Z] Finished download-and-extract step (success) 23:17:01 INFO - [mozharness: 2016-08-02 06:17:01.048047Z] Running populate-webroot step. 23:17:01 INFO - Running pre-action listener: _resource_record_pre_action 23:17:01 INFO - Running main action method: populate_webroot 23:17:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:17:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:17:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:17:01 INFO - '--tppagecycles', 23:17:01 INFO - '7'], 23:17:01 INFO - 'tests': ['tsvgm']}}, 23:17:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:17:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:17:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:17:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:17:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:01 INFO - 'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:17:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:17:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:01 INFO - 'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['damp', 'tps']}, 23:17:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:01 INFO - 'tests': ['damp', 'tps']}, 23:17:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['dromaeo_dom']}, 23:17:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:17:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['basic_compositor_video']}, 23:17:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:17:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['a11yr', 23:17:01 INFO - 'ts_paint', 23:17:01 INFO - 'tpaint', 23:17:01 INFO - 'sessionrestore', 23:17:01 INFO - 'sessionrestore_no_auto_restore', 23:17:01 INFO - 'tabpaint']}, 23:17:01 INFO - 'other-e10s': {'tests': ['a11yr', 23:17:01 INFO - 'ts_paint', 23:17:01 INFO - 'tpaint', 23:17:01 INFO - 'sessionrestore', 23:17:01 INFO - 'sessionrestore_no_auto_restore', 23:17:01 INFO - 'tabpaint']}, 23:17:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:17:01 INFO - 'ts_paint', 23:17:01 INFO - 'tpaint', 23:17:01 INFO - 'sessionrestore', 23:17:01 INFO - 'sessionrestore_no_auto_restore', 23:17:01 INFO - 'tabpaint']}, 23:17:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:17:01 INFO - 'ts_paint', 23:17:01 INFO - 'tpaint', 23:17:01 INFO - 'sessionrestore', 23:17:01 INFO - 'sessionrestore_no_auto_restore', 23:17:01 INFO - 'tabpaint']}, 23:17:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['a11yr', 23:17:01 INFO - 'ts_paint', 23:17:01 INFO - 'tpaint', 23:17:01 INFO - 'sessionrestore', 23:17:01 INFO - 'sessionrestore_no_auto_restore', 23:17:01 INFO - 'tabpaint']}, 23:17:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['a11yr', 23:17:01 INFO - 'ts_paint', 23:17:01 INFO - 'tpaint', 23:17:01 INFO - 'sessionrestore', 23:17:01 INFO - 'sessionrestore_no_auto_restore', 23:17:01 INFO - 'tabpaint']}, 23:17:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['tsvgx', 23:17:01 INFO - 'tsvgr_opacity', 23:17:01 INFO - 'tart', 23:17:01 INFO - 'tscrollx', 23:17:01 INFO - 'cart']}, 23:17:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:17:01 INFO - 'tsvgr_opacity', 23:17:01 INFO - 'tart', 23:17:01 INFO - 'tscrollx', 23:17:01 INFO - 'cart']}, 23:17:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:01 INFO - 'talos_options': ['--disable-e10s'], 23:17:01 INFO - 'tests': ['tp5o']}, 23:17:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:01 INFO - 'tests': ['tp5o']}, 23:17:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:01 INFO - 'talos_options': ['--disable-e10s', 23:17:01 INFO - '--xperf_path', 23:17:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:17:01 INFO - 'tests': ['tp5n']}, 23:17:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:17:01 INFO - 'talos_options': ['--xperf_path', 23:17:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:17:01 INFO - 'tests': ['tp5n']}}, 23:17:01 INFO - 'talos.zip': {'path': '', 23:17:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:17:01 INFO - Running post-action listener: _resource_record_post_action 23:17:01 INFO - [mozharness: 2016-08-02 06:17:01.064783Z] Finished populate-webroot step (success) 23:17:01 INFO - [mozharness: 2016-08-02 06:17:01.064873Z] Running create-virtualenv step. 23:17:01 INFO - Running pre-action listener: _resource_record_pre_action 23:17:01 INFO - Running main action method: create_virtualenv 23:17:01 INFO - Creating virtualenv /builds/slave/test/build/venv 23:17:01 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 23:17:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:17:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:17:01 INFO - Using real prefix '/usr' 23:17:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:17:01 INFO - Installing distribute.............................................................................................................................................................................................done. 23:17:03 INFO - Installing pip.................done. 23:17:03 INFO - Return code: 0 23:17:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:03 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 0x24f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', '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 23:17:03 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 23:17:03 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 23:17:03 INFO - Using env: {'DISPLAY': ':0', 23:17:03 INFO - 'HOME': '/home/cltbld', 23:17:03 INFO - 'LANG': 'en_US.UTF-8', 23:17:03 INFO - 'LANGUAGE': 'en_US:en', 23:17:03 INFO - 'LOGNAME': 'cltbld', 23:17:03 INFO - 'MAIL': '/var/mail/cltbld', 23:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:03 INFO - 'MOZ_NO_REMOTE': '1', 23:17:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:03 INFO - 'NO_EM_RESTART': '1', 23:17:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:03 INFO - 'PWD': '/builds/slave/test', 23:17:03 INFO - 'SHELL': '/bin/bash', 23:17:03 INFO - 'SHLVL': '1', 23:17:03 INFO - 'TERM': 'linux', 23:17:03 INFO - 'TMOUT': '86400', 23:17:03 INFO - 'USER': 'cltbld', 23:17:03 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:03 INFO - '_': '/tools/buildbot/bin/python'} 23:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:03 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 23:17:03 INFO - Cleaning up... 23:17:03 INFO - Return code: 0 23:17:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:03 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 0x24f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', '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 23:17:03 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 23:17:03 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 23:17:03 INFO - Using env: {'DISPLAY': ':0', 23:17:03 INFO - 'HOME': '/home/cltbld', 23:17:03 INFO - 'LANG': 'en_US.UTF-8', 23:17:03 INFO - 'LANGUAGE': 'en_US:en', 23:17:03 INFO - 'LOGNAME': 'cltbld', 23:17:03 INFO - 'MAIL': '/var/mail/cltbld', 23:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:03 INFO - 'MOZ_NO_REMOTE': '1', 23:17:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:03 INFO - 'NO_EM_RESTART': '1', 23:17:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:03 INFO - 'PWD': '/builds/slave/test', 23:17:03 INFO - 'SHELL': '/bin/bash', 23:17:03 INFO - 'SHLVL': '1', 23:17:03 INFO - 'TERM': 'linux', 23:17:03 INFO - 'TMOUT': '86400', 23:17:03 INFO - 'USER': 'cltbld', 23:17:03 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:03 INFO - '_': '/tools/buildbot/bin/python'} 23:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:04 INFO - Downloading/unpacking psutil>=3.1.1 23:17:04 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 23:17:04 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 23:17:04 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 23:17:04 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 23:17:04 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 23:17:04 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 23:17:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:17:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:17:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:17:06 INFO - Installing collected packages: psutil 23:17:06 INFO - Running setup.py install for psutil 23:17:06 INFO - building 'psutil._psutil_linux' extension 23:17:06 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 23:17:06 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 23:17:06 INFO - building 'psutil._psutil_posix' extension 23:17:06 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 23:17:06 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 23:17:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:17:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:17:06 INFO - Successfully installed psutil 23:17:06 INFO - Cleaning up... 23:17:06 INFO - Return code: 0 23:17:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:06 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 0x24f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', '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 23:17:06 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 23:17:06 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 23:17:06 INFO - Using env: {'DISPLAY': ':0', 23:17:06 INFO - 'HOME': '/home/cltbld', 23:17:06 INFO - 'LANG': 'en_US.UTF-8', 23:17:06 INFO - 'LANGUAGE': 'en_US:en', 23:17:06 INFO - 'LOGNAME': 'cltbld', 23:17:06 INFO - 'MAIL': '/var/mail/cltbld', 23:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:06 INFO - 'MOZ_NO_REMOTE': '1', 23:17:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:06 INFO - 'NO_EM_RESTART': '1', 23:17:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:06 INFO - 'PWD': '/builds/slave/test', 23:17:06 INFO - 'SHELL': '/bin/bash', 23:17:06 INFO - 'SHLVL': '1', 23:17:06 INFO - 'TERM': 'linux', 23:17:06 INFO - 'TMOUT': '86400', 23:17:06 INFO - 'USER': 'cltbld', 23:17:06 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:06 INFO - '_': '/tools/buildbot/bin/python'} 23:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:17:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:17:08 INFO - Installing collected packages: mozsystemmonitor 23:17:08 INFO - Running setup.py install for mozsystemmonitor 23:17:08 INFO - Successfully installed mozsystemmonitor 23:17:08 INFO - Cleaning up... 23:17:08 INFO - Return code: 0 23:17:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:17:08 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')]} 23:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:08 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')]} 23:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', '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 23:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:17:08 INFO - Using env: {'DISPLAY': ':0', 23:17:08 INFO - 'HOME': '/home/cltbld', 23:17:08 INFO - 'LANG': 'en_US.UTF-8', 23:17:08 INFO - 'LANGUAGE': 'en_US:en', 23:17:08 INFO - 'LOGNAME': 'cltbld', 23:17:08 INFO - 'MAIL': '/var/mail/cltbld', 23:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:08 INFO - 'MOZ_NO_REMOTE': '1', 23:17:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:08 INFO - 'NO_EM_RESTART': '1', 23:17:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:08 INFO - 'PWD': '/builds/slave/test', 23:17:08 INFO - 'SHELL': '/bin/bash', 23:17:08 INFO - 'SHLVL': '1', 23:17:08 INFO - 'TERM': 'linux', 23:17:08 INFO - 'TMOUT': '86400', 23:17:08 INFO - 'USER': 'cltbld', 23:17:08 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:08 INFO - '_': '/tools/buildbot/bin/python'} 23:17:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:09 INFO - Downloading/unpacking jsonschema==2.5.1 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17: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 23:17:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:17:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:17:11 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 23:17:11 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 23:17:11 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 23:17:11 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 23:17:11 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 23:17:11 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 23:17:11 INFO - Downloading functools32-3.2.3-2.tar.gz 23:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:17:11 INFO - warning: no files found matching '*.txt' 23:17:11 INFO - no previously-included directories found matching 'build' 23:17:11 INFO - no previously-included directories found matching 'dist' 23:17:11 INFO - no previously-included directories found matching '.git*' 23:17:11 INFO - Installing collected packages: jsonschema, functools32 23:17:11 INFO - Running setup.py install for functools32 23:17:11 INFO - warning: no files found matching '*.txt' 23:17:11 INFO - no previously-included directories found matching 'build' 23:17:11 INFO - no previously-included directories found matching 'dist' 23:17:11 INFO - no previously-included directories found matching '.git*' 23:17:11 INFO - Successfully installed jsonschema functools32 23:17:11 INFO - Cleaning up... 23:17:11 INFO - Return code: 0 23:17:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:17:11 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')]} 23:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:11 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')]} 23:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', '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 23:17:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:17:11 INFO - Using env: {'DISPLAY': ':0', 23:17:11 INFO - 'HOME': '/home/cltbld', 23:17:11 INFO - 'LANG': 'en_US.UTF-8', 23:17:11 INFO - 'LANGUAGE': 'en_US:en', 23:17:11 INFO - 'LOGNAME': 'cltbld', 23:17:11 INFO - 'MAIL': '/var/mail/cltbld', 23:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:11 INFO - 'MOZ_NO_REMOTE': '1', 23:17:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:11 INFO - 'NO_EM_RESTART': '1', 23:17:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:11 INFO - 'PWD': '/builds/slave/test', 23:17:11 INFO - 'SHELL': '/bin/bash', 23:17:11 INFO - 'SHLVL': '1', 23:17:11 INFO - 'TERM': 'linux', 23:17:11 INFO - 'TMOUT': '86400', 23:17:11 INFO - 'USER': 'cltbld', 23:17:11 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:11 INFO - '_': '/tools/buildbot/bin/python'} 23:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:17:11 INFO - Cleaning up... 23:17:11 INFO - Return code: 0 23:17:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:17:11 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')]} 23:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:11 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')]} 23:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:11 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 0x24f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', '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 23:17:11 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 23:17:11 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 23:17:11 INFO - Using env: {'DISPLAY': ':0', 23:17:11 INFO - 'HOME': '/home/cltbld', 23:17:11 INFO - 'LANG': 'en_US.UTF-8', 23:17:11 INFO - 'LANGUAGE': 'en_US:en', 23:17:11 INFO - 'LOGNAME': 'cltbld', 23:17:11 INFO - 'MAIL': '/var/mail/cltbld', 23:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:11 INFO - 'MOZ_NO_REMOTE': '1', 23:17:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:11 INFO - 'NO_EM_RESTART': '1', 23:17:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:11 INFO - 'PWD': '/builds/slave/test', 23:17:11 INFO - 'SHELL': '/bin/bash', 23:17:11 INFO - 'SHLVL': '1', 23:17:11 INFO - 'TERM': 'linux', 23:17:11 INFO - 'TMOUT': '86400', 23:17:11 INFO - 'USER': 'cltbld', 23:17:11 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:11 INFO - '_': '/tools/buildbot/bin/python'} 23:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:11 INFO - Downloading/unpacking blobuploader==1.2.4 23:17:11 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 23:17:11 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 23:17:11 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 23:17:11 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 23:17:11 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 23:17:11 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 23:17:13 INFO - Downloading blobuploader-1.2.4.tar.gz 23:17:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:17:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:17:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:17:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:17:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:17:14 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 23:17:14 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 23:17:14 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 23:17:14 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 23:17:14 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 23:17:14 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 23:17:14 INFO - Downloading docopt-0.6.1.tar.gz 23:17:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:17:14 INFO - Installing collected packages: blobuploader, requests, docopt 23:17:14 INFO - Running setup.py install for blobuploader 23:17:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:17:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:17:14 INFO - Running setup.py install for requests 23:17:15 INFO - Running setup.py install for docopt 23:17:15 INFO - Successfully installed blobuploader requests docopt 23:17:15 INFO - Cleaning up... 23:17:15 INFO - Return code: 0 23:17:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:15 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 0x24f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', '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 23:17:15 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 23:17:15 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 23:17:15 INFO - Using env: {'DISPLAY': ':0', 23:17:15 INFO - 'HOME': '/home/cltbld', 23:17:15 INFO - 'LANG': 'en_US.UTF-8', 23:17:15 INFO - 'LANGUAGE': 'en_US:en', 23:17:15 INFO - 'LOGNAME': 'cltbld', 23:17:15 INFO - 'MAIL': '/var/mail/cltbld', 23:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:15 INFO - 'MOZ_NO_REMOTE': '1', 23:17:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:15 INFO - 'NO_EM_RESTART': '1', 23:17:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:15 INFO - 'PWD': '/builds/slave/test', 23:17:15 INFO - 'SHELL': '/bin/bash', 23:17:15 INFO - 'SHLVL': '1', 23:17:15 INFO - 'TERM': 'linux', 23:17:15 INFO - 'TMOUT': '86400', 23:17:15 INFO - 'USER': 'cltbld', 23:17:15 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:15 INFO - '_': '/tools/buildbot/bin/python'} 23:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:17:15 INFO - Running setup.py (path:/tmp/pip-Gvzp6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:17:15 INFO - Running setup.py (path:/tmp/pip-KineX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:17:15 INFO - Running setup.py (path:/tmp/pip-tn6aul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:17:15 INFO - Running setup.py (path:/tmp/pip-F8vZsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:17:15 INFO - Running setup.py (path:/tmp/pip-ZeZfl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:17:15 INFO - Running setup.py (path:/tmp/pip-k9h6mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:17:15 INFO - Running setup.py (path:/tmp/pip-EaZAVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:17:15 INFO - Running setup.py (path:/tmp/pip-7_jb2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:17:15 INFO - Running setup.py (path:/tmp/pip-rwQoY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:17:16 INFO - Running setup.py (path:/tmp/pip-5tTXY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:17:16 INFO - Running setup.py (path:/tmp/pip-E8fFyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:17:16 INFO - Running setup.py (path:/tmp/pip-rs2sam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:17:16 INFO - Running setup.py (path:/tmp/pip-o_dqwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:17:16 INFO - Running setup.py (path:/tmp/pip-jOF0pW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:16 INFO - Running setup.py (path:/tmp/pip-S5Xkk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:17:16 INFO - Running setup.py (path:/tmp/pip-rcSmnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:17:16 INFO - Running setup.py (path:/tmp/pip-z3Ax92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:17:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:17:16 INFO - Running setup.py install for manifestparser 23:17:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:17:16 INFO - Running setup.py install for mozcrash 23:17:16 INFO - Running setup.py install for mozdebug 23:17:16 INFO - Running setup.py install for mozdevice 23:17:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Running setup.py install for mozfile 23:17:17 INFO - Running setup.py install for mozhttpd 23:17:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Running setup.py install for mozinfo 23:17:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Running setup.py install for mozInstall 23:17:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Running setup.py install for mozleak 23:17:17 INFO - Running setup.py install for mozlog 23:17:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Running setup.py install for moznetwork 23:17:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Running setup.py install for mozprocess 23:17:17 INFO - Running setup.py install for mozprofile 23:17:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:17:17 INFO - Running setup.py install for mozrunner 23:17:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:17:18 INFO - Running setup.py install for mozscreenshot 23:17:18 INFO - Running setup.py install for moztest 23:17:18 INFO - Running setup.py install for mozversion 23:17:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:17:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:17:18 INFO - Cleaning up... 23:17:18 INFO - Return code: 0 23:17:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:18 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 0x24f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', '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 23:17:18 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 23:17:18 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 23:17:18 INFO - Using env: {'DISPLAY': ':0', 23:17:18 INFO - 'HOME': '/home/cltbld', 23:17:18 INFO - 'LANG': 'en_US.UTF-8', 23:17:18 INFO - 'LANGUAGE': 'en_US:en', 23:17:18 INFO - 'LOGNAME': 'cltbld', 23:17:18 INFO - 'MAIL': '/var/mail/cltbld', 23:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:18 INFO - 'MOZ_NO_REMOTE': '1', 23:17:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:18 INFO - 'NO_EM_RESTART': '1', 23:17:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:18 INFO - 'PWD': '/builds/slave/test', 23:17:18 INFO - 'SHELL': '/bin/bash', 23:17:18 INFO - 'SHLVL': '1', 23:17:18 INFO - 'TERM': 'linux', 23:17:18 INFO - 'TMOUT': '86400', 23:17:18 INFO - 'USER': 'cltbld', 23:17:18 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:18 INFO - '_': '/tools/buildbot/bin/python'} 23:17:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:17:18 INFO - Running setup.py (path:/tmp/pip-bYoLUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:17:18 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)) 23:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:17:18 INFO - Running setup.py (path:/tmp/pip-cmAGk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:17:18 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)) 23:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:17:18 INFO - Running setup.py (path:/tmp/pip-_z8DOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:17:18 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)) 23:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:17:18 INFO - Running setup.py (path:/tmp/pip-zx078s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:17:18 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)) 23:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:17:18 INFO - Running setup.py (path:/tmp/pip-kOjxqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:17:18 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)) 23:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:17:18 INFO - Running setup.py (path:/tmp/pip-NSgdCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:17:19 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)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:17:19 INFO - Running setup.py (path:/tmp/pip-wAAtSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:17:19 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)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:17:19 INFO - Running setup.py (path:/tmp/pip-8iTXOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:17:19 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)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:17:19 INFO - Running setup.py (path:/tmp/pip-yINfOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:17:19 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)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:17:19 INFO - Running setup.py (path:/tmp/pip-ElL0XX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:17:19 INFO - Running setup.py (path:/tmp/pip-4JXzqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:17:19 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)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:17:19 INFO - Running setup.py (path:/tmp/pip-gt8bGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:17:19 INFO - Running setup.py (path:/tmp/pip-4A__pN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:17:19 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)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:17:19 INFO - Running setup.py (path:/tmp/pip-uEGIZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:19 INFO - Running setup.py (path:/tmp/pip-Rjxg9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:17:19 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)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:17:19 INFO - Running setup.py (path:/tmp/pip-0nS3WI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:17:19 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)) 23:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:17:19 INFO - Running setup.py (path:/tmp/pip-ert0cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:17:19 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)) 23:17:19 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)) 23:17:19 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)) 23:17:19 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)) 23:17:19 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)) 23:17:19 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)) 23:17:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:17:19 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 23:17:19 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 23:17:19 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 23:17:19 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 23:17:19 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 23:17:19 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 23:17:21 INFO - Downloading blessings-1.6.tar.gz 23:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:17:21 INFO - Installing collected packages: blessings 23:17:21 INFO - Running setup.py install for blessings 23:17:21 INFO - Successfully installed blessings 23:17:21 INFO - Cleaning up... 23:17:22 INFO - Return code: 0 23:17:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:17:22 INFO - Reading from file tmpfile_stdout 23:17:22 INFO - Current package versions: 23:17:22 INFO - argparse == 1.2.1 23:17:22 INFO - blessings == 1.6 23:17:22 INFO - blobuploader == 1.2.4 23:17:22 INFO - docopt == 0.6.1 23:17:22 INFO - functools32 == 3.2.3-2 23:17:22 INFO - jsonschema == 2.5.1 23:17:22 INFO - manifestparser == 1.1 23:17:22 INFO - mozInstall == 1.12 23:17:22 INFO - mozcrash == 0.17 23:17:22 INFO - mozdebug == 0.1 23:17:22 INFO - mozdevice == 0.48 23:17:22 INFO - mozfile == 1.2 23:17:22 INFO - mozhttpd == 0.7 23:17:22 INFO - mozinfo == 0.9 23:17:22 INFO - mozleak == 0.1 23:17:22 INFO - mozlog == 3.3 23:17:22 INFO - moznetwork == 0.27 23:17:22 INFO - mozprocess == 0.23 23:17:22 INFO - mozprofile == 0.28 23:17:22 INFO - mozrunner == 6.12 23:17:22 INFO - mozscreenshot == 0.1 23:17:22 INFO - mozsystemmonitor == 0.3 23:17:22 INFO - moztest == 0.7 23:17:22 INFO - mozversion == 1.4 23:17:22 INFO - psutil == 3.1.1 23:17:22 INFO - requests == 1.2.3 23:17:22 INFO - wsgiref == 0.1.2 23:17:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:22 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 0x24f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', '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 23:17:22 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 23:17:22 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 23:17:22 INFO - Using env: {'DISPLAY': ':0', 23:17:22 INFO - 'HOME': '/home/cltbld', 23:17:22 INFO - 'LANG': 'en_US.UTF-8', 23:17:22 INFO - 'LANGUAGE': 'en_US:en', 23:17:22 INFO - 'LOGNAME': 'cltbld', 23:17:22 INFO - 'MAIL': '/var/mail/cltbld', 23:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:22 INFO - 'MOZ_NO_REMOTE': '1', 23:17:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:22 INFO - 'NO_EM_RESTART': '1', 23:17:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:22 INFO - 'PWD': '/builds/slave/test', 23:17:22 INFO - 'SHELL': '/bin/bash', 23:17:22 INFO - 'SHLVL': '1', 23:17:22 INFO - 'TERM': 'linux', 23:17:22 INFO - 'TMOUT': '86400', 23:17:22 INFO - 'USER': 'cltbld', 23:17:22 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:22 INFO - '_': '/tools/buildbot/bin/python'} 23:17:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:22 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)) 23:17:22 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)) 23:17:22 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)) 23:17:22 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)) 23:17:22 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)) 23:17:22 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)) 23:17:22 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)) 23:17:22 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)) 23:17:22 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)) 23:17:22 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)) 23:17:22 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)) 23:17:22 INFO - Cleaning up... 23:17:22 INFO - Return code: 0 23:17:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:17:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:17:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:17:22 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 0x24f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', '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 23:17:22 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 23:17:22 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 23:17:22 INFO - Using env: {'DISPLAY': ':0', 23:17:22 INFO - 'HOME': '/home/cltbld', 23:17:22 INFO - 'LANG': 'en_US.UTF-8', 23:17:22 INFO - 'LANGUAGE': 'en_US:en', 23:17:22 INFO - 'LOGNAME': 'cltbld', 23:17:22 INFO - 'MAIL': '/var/mail/cltbld', 23:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:22 INFO - 'MOZ_NO_REMOTE': '1', 23:17:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:22 INFO - 'NO_EM_RESTART': '1', 23:17:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:22 INFO - 'PWD': '/builds/slave/test', 23:17:22 INFO - 'SHELL': '/bin/bash', 23:17:22 INFO - 'SHLVL': '1', 23:17:22 INFO - 'TERM': 'linux', 23:17:22 INFO - 'TMOUT': '86400', 23:17:22 INFO - 'USER': 'cltbld', 23:17:22 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:22 INFO - '_': '/tools/buildbot/bin/python'} 23:17:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:17:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:17:22 INFO - Cleaning up... 23:17:22 INFO - Return code: 0 23:17:22 INFO - Running post-action listener: _resource_record_post_action 23:17:22 INFO - Running post-action listener: _start_resource_monitoring 23:17:22 INFO - Starting resource monitoring. 23:17:22 INFO - [mozharness: 2016-08-02 06:17:22.681988Z] Finished create-virtualenv step (success) 23:17:22 INFO - [mozharness: 2016-08-02 06:17:22.682427Z] Running install step. 23:17:22 INFO - Running pre-action listener: _resource_record_pre_action 23:17:22 INFO - Running main action method: install 23:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:17:22 INFO - Reading from file tmpfile_stdout 23:17:22 INFO - Detecting whether we're running mozinstall >=1.0... 23:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:17:22 INFO - Reading from file tmpfile_stdout 23:17:22 INFO - Output received: 23:17:22 INFO - Usage: mozinstall [options] installer 23:17:22 INFO - Options: 23:17:22 INFO - -h, --help show this help message and exit 23:17:22 INFO - -d DEST, --destination=DEST 23:17:22 INFO - Directory to install application into. [default: 23:17:22 INFO - "/builds/slave/test"] 23:17:22 INFO - --app=APP Application being installed. [default: firefox] 23:17:22 INFO - mkdir: /builds/slave/test/build/application 23:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:17:39 INFO - Reading from file tmpfile_stdout 23:17:39 INFO - Output received: 23:17:39 INFO - /builds/slave/test/build/application/firefox/firefox 23:17:39 INFO - Running post-action listener: _resource_record_post_action 23:17:39 INFO - [mozharness: 2016-08-02 06:17:39.903806Z] Finished install step (success) 23:17:39 INFO - [mozharness: 2016-08-02 06:17:39.904082Z] Running run-tests step. 23:17:39 INFO - Running pre-action listener: _resource_record_pre_action 23:17:39 INFO - Running main action method: run_tests 23:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:17:39 INFO - Python 2.7.3 23:17:39 INFO - Return code: 0 23:17:39 INFO - grabbing minidump binary from tooltool 23:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:17:39 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 0x27b13b0>, '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 0x27b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b2740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:17:39 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 23:17:39 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 23:17:39 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 23:17:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:17:39 INFO - Return code: 0 23:17:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:17:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:17:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:17:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:17:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 23:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 23:17:39 INFO - Using env: {'DISPLAY': ':0', 23:17:39 INFO - 'HOME': '/home/cltbld', 23:17:39 INFO - 'LANG': 'en_US.UTF-8', 23:17:39 INFO - 'LANGUAGE': 'en_US:en', 23:17:39 INFO - 'LOGNAME': 'cltbld', 23:17:39 INFO - 'MAIL': '/var/mail/cltbld', 23:17:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:17:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:17:39 INFO - 'MOZ_NO_REMOTE': '1', 23:17:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:17:39 INFO - 'NO_EM_RESTART': '1', 23:17:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:17:39 INFO - 'PWD': '/builds/slave/test', 23:17:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:17:39 INFO - 'SHELL': '/bin/bash', 23:17:39 INFO - 'SHLVL': '1', 23:17:39 INFO - 'TERM': 'linux', 23:17:39 INFO - 'TMOUT': '86400', 23:17:39 INFO - 'USER': 'cltbld', 23:17:39 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633', 23:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:17:39 INFO - '_': '/tools/buildbot/bin/python'} 23:17:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 23:17:40 INFO - mozversion application_buildid: 20160801203011 23:17:40 INFO - mozversion application_changeset: af62b45afe58e1e92e90b9df9c7c3f66f8bac60e 23:17:40 INFO - mozversion application_display_name: Nightly 23:17:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:17:40 INFO - mozversion application_name: Firefox 23:17:40 INFO - mozversion application_remotingname: firefox 23:17:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:17:40 INFO - mozversion application_vendor: Mozilla 23:17:40 INFO - mozversion application_version: 51.0a1 23:17:40 INFO - mozversion platform_buildid: 20160801203011 23:17:40 INFO - mozversion platform_changeset: af62b45afe58e1e92e90b9df9c7c3f66f8bac60e 23:17:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:17:40 INFO - mozversion platform_version: 51.0a1 23:17:40 INFO - using testdate: 1470118660 23:17:40 INFO - actual date: 1470118660 23:17:40 INFO - starting webserver on 'localhost:44379' 23:17:40 INFO - SUITE-START | Running 2 tests 23:17:40 INFO - TEST-START | dromaeo_css 23:17:40 INFO - operating with platform_type : linux_ 23:17:40 INFO - Initialising browser for dromaeo_css test... 23:17:40 INFO - TEST-INFO | started process 24883 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkD70C0/profile http://localhost:44379/getInfo.html) 23:17:44 INFO - PROCESS | 24883 | __metrics Screen width/height:1600/1200 23:17:44 INFO - PROCESS | 24883 | colorDepth:24 23:17:44 INFO - PROCESS | 24883 | Browser inner width/height: 1024/697 23:17:44 INFO - PROCESS | 24883 | __metrics 23:17:45 INFO - PROCESS | 24883 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:17:45 INFO - PROCESS | 24883 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:17:46 INFO - TEST-INFO | 24883: exit 0 23:17:46 INFO - Browser initialized. 23:17:46 INFO - Running cycle 1/1 for dromaeo_css test... 23:17:46 INFO - TEST-INFO | started process 24962 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkD70C0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:17:58 INFO - PROCESS | 24962 | RSS: Main: 155578368 23:17:58 INFO - PROCESS | 24962 | 23:18:49 INFO - PROCESS | 24962 | 1470118729308 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] 23:20:58 INFO - PROCESS | 24962 | Cycle 1(1): loaded http://localhost:44379/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44379/tests/dromaeo/cssquery-ext.html) 23:20:59 INFO - PROCESS | 24962 | RSS: Main: 276881408 23:20:59 INFO - PROCESS | 24962 | 23:24:02 INFO - PROCESS | 24962 | Cycle 1(1): loaded http://localhost:44379/tests/dromaeo/cssquery-ext.html (next: http://localhost:44379/tests/dromaeo/cssquery-jquery.html) 23:24:02 INFO - PROCESS | 24962 | RSS: Main: 248868864 23:24:02 INFO - PROCESS | 24962 | 23:27:04 INFO - PROCESS | 24962 | Cycle 1(1): loaded http://localhost:44379/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44379/tests/dromaeo/cssquery-mootools.html) 23:27:05 INFO - PROCESS | 24962 | RSS: Main: 230825984 23:27:05 INFO - PROCESS | 24962 | 23:30:07 INFO - PROCESS | 24962 | Cycle 1(1): loaded http://localhost:44379/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44379/tests/dromaeo/cssquery-prototype.html) 23:30:07 INFO - PROCESS | 24962 | RSS: Main: 240644096 23:30:07 INFO - PROCESS | 24962 | 23:33:10 INFO - PROCESS | 24962 | Cycle 1(1): loaded http://localhost:44379/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44379/tests/dromaeo/cssquery-yui.html) 23:33:11 INFO - PROCESS | 24962 | RSS: Main: 187916288 23:33:11 INFO - PROCESS | 24962 | 23:36:14 INFO - PROCESS | 24962 | Cycle 1(1): loaded http://localhost:44379/tests/dromaeo/cssquery-yui.html (next: http://localhost:44379/tests/dromaeo/cssquery-dojo.html) 23:36:14 INFO - PROCESS | 24962 | RSS: Main: 279552000 23:36:14 INFO - PROCESS | 24962 | 23:36:14 INFO - PROCESS | 24962 | __start_tp_report 23:36:14 INFO - PROCESS | 24962 | _x_x_mozilla_page_load 23:36:14 INFO - PROCESS | 24962 | _x_x_mozilla_page_load_details 23:36:14 INFO - PROCESS | 24962 | |i|pagename|runs| 23:36:14 INFO - PROCESS | 24962 | |0;dojo.html;3077.707617776312;3082.121595345326;3105.347876945841;3110.237991692036;3146.2606287522444;5260.342457192851;5275.630705850592;5298.708571028592;5322.49436303551;5338.893222135564;5262.0265250928505;5304.522592966632;5362.544183744377;5384.973075134619;5457.645253058548;4714.3164241185;4786.2581299898575;4788.874614465615;4800.135975524404;4962.925556116661;4875.024995000997;4904.2398428243505;4904.48502907195;4923.990581930696;4930.260460930853;4804.447488538793;4808.422989241296;4860.270959356089;4867.026594681078;4930.235813448903;4772.76136193189;4775.092732380836;4795.6163506919365;4866.537678920497;4928.137575924235;4786.641001924835;4819.445763737145;4825.782839772219;4840.298156767249;4854.975725121387;5191.922121168185;5285.603579731497;5311.920321195185;5353.116735738598;5379.65032272901;5763.567732420043;5771.797987070474;5798.217240672498;5808.7095645218;5818.476337129637;5819.621724587888;5836.5622578306375;5858.970705146447;5873.765049398019;5885.382034886361;5222.817201397933;5224.921626175573;5264.78940842362;5275.155975043975;5452.2094296327205;5832.562557808182;5833.38749431312;5835.533157347402;5837.416258374214;5841.4450627190345;5539.086050801573;5601.831945041656;5602.4957753802355;5603.971980140154;5607.299087614048;5605.131204663284;5638.746256418471;5682.545396368363;5684.34630017545;5696.202531645573;5697.401772813795;5702.087665973424;5706.029974904276;5706.058500347396;5745.971270143623;5657.12314591239;5702.914456283159;5709.400512946164;5716.056850619684;5718.428157184248;4250.298700714409;4322.632576231055;4326.134773045404;4393.934090988638;4401.119776044739;3921.745086569421;3942.4677668514382;3944.9408258876138;3945.0926286954164;3945.230680017369;5332.466753324637;5333.146696528537;5335.786568537293;5335.893282134336;5349.438308977579;5571.415001424792;5642.887142257214;5652.236948011966;5658.377578466366;5710.057840456361;5325.334333208349;5355.839324820262;5357;5363.356397232357;5376.7580458878665;4898.069366820293;4905.9754701226975;4922.852314430573;4938.802447902043;4959.057779021974;5711.514521265739;5737.655740655574;5751;5786.508146807484;5874.9118763218585;5646.858828529155;5647.5481961442165;5650.124230744241;5653;5660.349059858005;5785.247917770663;5807.651540907559;5810.477057064884;5812.273465816773;5815.982203114353;5470.343558772973;5638.351589567253;5642.153676948491;5665.49010589049;5772.740226689725;5757.222774925332;5786.450287222707;5794.797182098607;5795.82612521625;5797.971010144923;5685.71571421419;5693.174489698888;5701.173329867229;5701.515371193329;5703.7433315500075;5466.234727138283;5523.972380138073;5541.695206763667;5576.5817563683695;5628.521575666115;5477.67133971963;5536.944630553804;5538.723063846872;5538.833834984957;5543.6396634218645;5449;5605.80379686709;5661.179129026351;5668.291463567054;5675.091985282335;5418.322709661293;5469.9179512306555;5475.397706252389;5512.862178445411;5552.916706249329 23:36:14 INFO - PROCESS | 24962 | |1;ext.html;557.6091420840279;583.2534356024289;607.6354187487507;619.7892716476397;637.7289651897942;38631.22737545247;39893.60106398935;40212.39681404781;40288;41678.16643667125;40606.7969660152;40884.79557602215;40942.18115637693;40968.79515602425;40983;8902.2433093187;9062.682806101788;9062.773430664252;9063.184313411792;9069.455832650063;8972;9064.45613263203;9078.682246121387;9080.40977336471;9085.454872707625;9117.63529458824;9163.679271225508;9170.679026234084;9172.495512746797;9180.954095229514;9032.232260257886;9065.954670226673;9069.229115525188;9111.635534578609;9115.954420227923;8956;9020.14308640678;9027.14242146995;9029.954850225773;9034.819303613956;39213.80393098016;39594.20811583752;39650.603493964976;39658.80170599157;39664;3191.3457741163006;3323.3685599735973;3363.630000699921;3404.914877128067;3565.4830049642915;29791.404171916656;30148.09555713332;30473.23816904595;30831.84584077088;31366.686333136604;4261.2329780639475;4389.473263208403;4438.556144385568;4457.130859482402;4494.258447356182;333666.99999999756;334431;336972;337293;338674;49393.50606493961;49683.50316496861;50267.74866125646;50358.49641503611;50408.495915041116;48864.75567622139;49565.25652115149;49598.75200624046;49780.75109624429;50007.499925001015;14726.263686815832;15549.844501555066;15562.22188890551;15564.377424903103;15606.29771660278;12060.51757929673;12121.757564848655;12128.636140915787;12133.757324853454;12136.514539418324;13363;13725.245111518961;13785.655358616115;13880.4447822088;14036.508722194674;5787.623804452697;5866.208061911672;5910.8817823643285;5938.61399009063;6080.878382432326;925.6066171876926;981.5435822342645;991.7371896447447;1035.414990530348;1107.9224454288283;30725.69274307273;31007.224819379244;31016.68983310183;31129.533057004162;31183;991.4249735153594;1025.1286406554368;1047.3872784421199;1074.8602681651334;1095.9397233152254;15950.122743249001;16018.75971860423;16190.376600289957;16340;16357.427490037791;518447;519339;519996;520998;521904;52533.21200182;52774;52793.472065279624;52814.73592632089;52865.20702189393;1171.8183681529376;1234.9876501234874;1286.5304163980268;1287.7617640736494;1301.8242537257538;29379;29860.402791944474;30250.394992100475;30277.243068923453;30853;46402.535974639824;47177;47226;47270.76364618155;47347;453891;454056;455214;458733;459298;33189.33621327608;36881.63118368782;37946;38769.80615096907;38776.6122338773;42205.155896882505;42263.7886810564;42302.153956920076;42975.785121074194;43408;35694.464583030756;37156.814215928745;37587.8719374954;37998.43002355022;38998;37135.257294853414;37209.4418583716;37681.623183768905;37695.2460950774;37775.433368500046;37021.62978370182;37059.25881482409;37282;37356.626433736405;37424.812875935444;32725.672743273215;34607.13482162966;34879;35357.64642353646;35373.64626353703;26081.217563472368;26361.209163725118;26416.471670566098;26623.733762662127;26862.462750745268 23:36:14 INFO - PROCESS | 24962 | |2;jquery.html;353.46273664030656;354.61524246192886;364.67361711268416;377.5092379906121;382.4550016226876;17487.562810929718;18016;18333.44999650011;18410.539736506562;18432.539186520342;16963.91518042408;17078.146092695377;17357.47927562172;17419.216135273913;17735.113244337765;5153.536181743642;5326.7070311132875;5331.147016477365;5331.2269720890445;5334.093204155283;5159.1229490986625;5213.113770658997;5241.973790131044;5256.185291279858;5285.524302812746;5246.055709972204;5246.685198888079;5255.421903590602;5255.448177941318;5257.36911570612;5142.74286285688;5206.947930520703;5238.476152384769;5260.342457192832;5274.182501712241;5100.438951715309;5112.923306150411;5116.130257856136;5116.846494605168;5121.897562048775;14123.646908827259;14353.282335883165;21858.672119918214;22087.33737987863;22127.33617991463;2281.133169395636;2436.098643501898;2543;2792.9162125136277;2955.3202763364548;9367.063293670646;9653.324267301292;9987.950060249725;10015.59937602495;10250.641227557075;1904.7047707605343;1906.2946709717355;1937.728717979484;1949.1716020691292;1957.3736404350614;78253.60873195597;79308.60345698292;79607;80436.59781701054;82219.58890205571;10214.803817179341;10364.896351036545;10570.682879513626;10645.84031239532;10693.518791654238;14883.404663813542;14897.47858824936;14915.850841491663;14919.179445130405;14954.850451495564;7604.315611594872;7690.615469226516;7810.1799311072655;7912.32745216663;7928.088269849041;7568.8486230276185;7669.6548655309525;7904.367650587939;7908.169642187602;7939.722109726132;4347.130573885235;4468.419105516262;4544.636429085665;4560.452745670541;4564.497905230422;1795.4882858385558;1863.543431859203;1888.5845114075157;1896.4784684211952;1904.3144467991513;798.7084714079435;933.8365785987424;939.0656296984544;1010.1666125446536;1024.7130803375067;18480.168392422373;19497.22011119568;19515.707264391047;19547.706784398244;19551.413457596296;942.4533770413144;949.3734135470556;969.7284760267137;979.3487330924997;1004.2969921055145;8903.910960890309;10617.469126543796;10917.56329746817;10920.836187457195;10930.289531180448;519531;519678;521240;522973;525556;90430.09569904348;91427.54286228526;92063;92552;93938.5303073494;938.8075444533854;951.3911096897991;960.524540352516;974.8294048541619;975.6390135649855;16234.918825405797;16381.344746210018;17362.565935851704;18177.909110454362;18674.253029879197;41634.375484367156;44618.3307250398;44717.32924006208;44842.77578612086;45051;56349.71825141012;57447;58818;59303;59945.40054599398;8166.469179503313;8222.84612387203;8236.299914507334;8372.581370931552;8381.706640267561;8804.196467341266;9433.103855133751;12148.331841748788;12196.207246528948;12335.814962775386;11147.163962702667;11505.309681419141;11880.584179553674;12070.939645301718;12080.093992950387;11322.60370887015;11328.32030078198;11447.14146439037;11769.47037383337;12179.878201217874;11377.715557111138;11817.113716471471;11909.285442873455;11950.641480755592;12075.818862716891;8080.1919808021075;8286.171382861665;8289.95855020721;8308.127646597262;8653.61058752345;8964.641414343354;10772.946135269587;11020.50407731638;11222.943885280521;11732.94133529327 23:36:14 INFO - PROCESS | 24962 | |3;mootools.html;1204.470033185398;1226.4603574427251;1256.7800634888893;1306.9609660320045;1532.3410933298678;8172.9591352043235;8546.931633545806;8630.266427353667;8826.338024648161;8835.734927952171;8617.13828617138;8668.176523230299;8714.17215364541;8721.215090641841;8731.781705457366;2278.3848360942493;2295.437617783637;2296.5866144094057;2303.320520446464;2304.9193278235266;2264.1283106004225;2286.4855407533337;2310;2314.1090680088205;2316.2819525946925;2273.59075366434;2295.070496448941;2300.1374484568287;2318.5710643531;2321.616933206019;2243.596152692515;2256.2103263857603;2259.186692790594;2260.107257633242;2260.49138943738;2235.709357783494;2238.8880555972137;2242.461809165805;2245.674377215311;2247.5617254635354;8538.87191692125;8604.612792424294;8726.432781869158;8729.432586881832;8750.562471876445;4970.801167953277;5180.715060671661;5208.583313334924;5214.139666954948;5214.556762675177;3198.5042318440446;3345.7156141727983;3400.4559270516847;3401.812900290483;3434.3131373725355;1207.3419986107592;1220.0971281251898;1223.4922507159517;1242.3974372429363;1255.9309237991906;12281.447334869952;12346.629601111981;12414.131010829245;12414.75170496585;12419.130660853654;4058.2695114879016;4227.978860105742;4358.280883654225;4400.075984043387;4401.361802538678;9419.481928493999;9422.057794220524;9422.764430889283;9430.10414010668;9451.054894510631;5053.545180933734;5056.646034777604;5061.924071138936;5062.341895553554;5066.240063990357;4169.708120431541;4549.772511374418;4692.202325604587;4711.764411779466;4735.289706543977;3455.395305821471;3545.4681797730545;3560.287942411528;3631.5823680276576;3650.1604630935035;1110.4836251143095;1115.2583531951325;1139.2196345503191;1142.440204299887;1148.534843388429;724.5109551053018;781.7185813107277;868.3140319147802;870.3298460185622;904.1771987491351;5521.944780552223;5633.183188437653;5680.971595141998;5717.971410142923;5863.413658634187;427.20753003179277;434.0624251616556;434.2834323366471;908.5003248213457;971.6494073238205;3992.640662340403;4006.419069234945;4032.959670403317;4039.171969746195;4077.6941729369823;10232.437215953194;10916.781664366827;11370.488328025258;11398.886011139948;11403.03074238699;10673.35959842412;11112.16658750597;11150.330980141218;11157.721056973478;11160.497777600027;7794.727184548514;8021.639026243833;8058.637361318754;8097.919020809716;8195.67217311301;5525.917111243254;5643.717814109196;5719.942800571941;5802.970985145047;5932.085102988864;9597.376170548892;9878.357906736039;9955.20358371314;10215.897841021495;10290.3825770454;10054.949725251327;10139.493025348851;10311.123554497952;10433.791324173322;10534.051935325857;6960.686769095504;7025.156981162293;7579.393648507996;7867.09528404218;8037.598120093855;8466.322694184328;8595.18345757152;8601.569921504026;8603.569821509025;8607.096254893146;7322.96338518304;7454.701811927463;7480.812979675552;7572.924270757222;7593.5823529706395;7400.111986561647;7461;7640.8471830564185;7643.197464266172;7727.574983375968;6668.933310666831;6775.119234499681;6781.254062053072;6791.864162716817;6799.7620083298825;4849.612031037579;4860.128850392553;4919.926201106915;4983.501649834988;4986.850394488171;4889.90220195587;4944.851654450372;5064;5097.745112744422;5144.897102057863 23:36:14 INFO - PROCESS | 24962 | |4;prototype.html;307.5678671466589;315.51095801507665;324.88628979857043;326.3391631945309;327.7787493441926;5102.744862756866;5131.666441681288;5188.273641690166;5200.765965531551;5256.395514515832;5130.897382052357;5174.715390653512;5180.274761533388;5181.922271165936;5198.740062996854;4075.3071977763702;4083.5099788025477;4089.3457046872504;4093.713440059182;4097.631213190813;3985.6448807405477;4034.5158580970324;4073.450084238634;4088.8568900088503;4096.221717873595;4010.498687664042;4015.176888737816;4045.2920738870725;4053.3312003519386;4057.3913912913154;4038.212548553004;4043.65628921542;4044.534878488982;4054.1688953764406;4056.9594304057023;3610.295992281506;3622.945655815165;3630.709543236535;3632.436972269276;3650.361186792301;4905.447670735402;5026.899462010776;5034.320366750479;5063.949360506384;5095.439501654815;4645.163870503308;4655.534446555352;4656.65075119364;4660.39414876064;4662.883427914296;5347.385050719139;5376.865578360533;5381.650192737459;5382.219578161184;5390.811321603725;3872.7482713623526;3935.4293627423863;3964.1873415949663;3970.8411663533425;3987.1626958338493;5298.377303234924;5363.704996225167;5564.833055008316;5604.159376093618;5609.775608975636;3974.423708562242;3983.402489626579;3983.50206224222;3984.820683069269;3988.5470435681846;3849.6517124554;3951.9604803952166;4015.4980627421573;4033.9193216135513;4046.858359957387;4569.103821913935;4608.456167184031;4634.536546345406;4645.581897629229;4648.302754586772;5423.322084739408;5430.755615997211;5437.184422336682;5453.372862120827;5456.563474921997;1363.9454421823016;1407;1414.731201071793;1427.9500217492546;1430.7210094031564;891.6477991193572;917.8990311065777;918.6509126532009;925.4863550729275;928.5496534180917;4660.580547750718;4661.417322834645;4677.082396217603;4680.531946805292;4692.272697731857;4834.371531700856;4880.780364883589;4889.951100489021;4893.828715994923;4913.459519452857;4663.924553573627;4676.972254826075;4691.483936766953;4722.480527142012;4736.905261894743;2709.4716530276405;2939.6913324101088;2956.054062699958;2959.718826711459;2965.54034124711;5727.799527016535;5740.65556066629;5743.10981797822;5746.885062298814;5754.510866576305;5517.282753242133;5640.32316122068;5701.8289451315695;5708.543316534668;5711.45741154598;5665.745041473144;5707.885842283048;5714.685692286964;5731.312242530923;5734.598578099576;5542.251796007487;5559.360673522597;5564.304461942257;5566.192902029264;5599.900037485942;5249.685018898878;5447.782088716565;5481.369642491165;5536.339098270583;5563.5271001963665;5729.4843464088435;5739.770409183586;5755.798547050833;5757.222774925332;5757.445489717669;3831.5593706724085;3939.330313846596;3944.5069366329208;3952.4268980998168;3963.4649322341115;5465.207544905887;5491.9725401374335;5495.230667706604;5499.065158923073;5506.889862202814;5358.303420555459;5379.838604841861;5406.918896216638;5413.810516631899;5423.972880135732;5123.769430375568;5286.7356632169;5309.017831701148;5352.384475785178;5377.758000889891;5286.62993590445;5287.788488460418;5298;5303.655262407931;5307.601929855199;5372.435894231049;5380.730963451733;5388.191771234347;5389;5400.783968641211;5074;5284.735763211747;5297.073012222922;5334.306540149756;5366.463353664602 23:36:14 INFO - PROCESS | 24962 | |5;yui.html;662.3707477896;687.8830598798204;706.8197609609549;721.8808896532072;758.9715934908199;15414.383424663014;16485;16535.751963720522;16600.66798664026;16609.33562657494;13246.735065298688;13410;13410.195388276687;13435.19388836668;13439;575.680497323985;709.2269426325305;739.2238149942566;763.7708687393788;769.1424062170695;712.5653351455608;715.5027256057017;720.8378114924152;722.2596838240819;724.69562783631;777.7122464688073;782.194339829976;782.2060608482367;783.1737516919648;783.475071701961;574.2649408756798;775.9883601745922;777.3120788102542;778.2878666020584;778.6963084397062;557.8967890940148;571.0263999880194;571.8227349521661;573.7676241122352;573.8909607174623;14437;14439.639009024753;14440.277986100653;14488.20314882681;14507.057041292283;13687.452501899912;14236.786448203286;14644.70710585793;14695.559133226021;14700.191489468076;13319.733605327936;13551.39018744159;13639.863601363959;13663.863361366459;14256.429742810275;4327.805248763813;4488.483824360175;4547.067851090552;4568.2462393705;4571.588557029883;78809.81785273283;79032;79257.60371198106;79848;79895;10605.151587872951;10633.893661063445;10906.945465272782;10953.726156846142;11264.54941802335;20231.393058208276;20256.493587660425;20293.594128117355;20442.342495475492;20608;8116.5941702914615;8216.548089855116;8391.118932512121;8499.36254780894;8558.700445484377;4538;4596.310553416947;4720.504347043511;4777.880552986203;4928.408590969035;3417.179876829543;3568.072301201706;3571.160777217352;3572.4284114541547;3576.803275819855;561.284362437895;570.0850135532411;583.9649621022752;586.2700937333047;587.9059350503899;588.3733823477983;599.8050633544028;609.3966972696998;656.6421300391298;663.3300366629738;9270.814583708423;9556.331056825955;9662.227021838236;9751.90248097524;9845.113939745313;568.7099579214548;617.2654541096082;617.3517806303364;671.2280876991499;691.2922994408096;4276.2089013532595;4399.978000109979;4528.615067719281;4620.3993480847275;4777.784999675023;109720;114541.42729286467;118893;119131;123427;83613;100769.05010568752;101047;101556.49221753991;102686;75045;82072.58963705144;83845.58077209575;84435;84875;9149.451032938045;9442.622295108393;9726.70819875405;9899;9916.603335866484;45807;50296.497035029184;50355;50659.24011139762;52098.218526722885;59969.70015149896;71963.28036719775;72045.27954720595;73342.63328683322;73824.63087684527;12531.93734031324;12555.372231388212;12744.6813829655;13100.21398716042;13565.253911034988;14787.778183327473;15059.849401505844;15064.472743453927;15080.698386032438;15248.466303679317;13034.57055336741;13107.14803537767;13108.541201057917;13151.318972353503;13471.191728495929;13918;14274.643133921734;14786;14787;15253.6186595336;13011.414486347947;13425.66435839112;13502.527411540941;13741.725165496833;13895.166290022631;10880.619178329038;10892.641375660281;10904.890951090387;10930.617428389969;11332.490037948477;9968.850467242852;10265.948670256601;10453.42506162138;11142.387168705798;11452.713682158013 23:36:14 INFO - PROCESS | 24962 | __end_tp_report 23:36:14 INFO - PROCESS | 24962 | __start_cc_report 23:36:14 INFO - PROCESS | 24962 | _x_x_mozilla_cycle_collect,1073 23:36:14 INFO - PROCESS | 24962 | __end_cc_report 23:36:14 INFO - PROCESS | 24962 | __startTimestamp1470119774267__endTimestamp 23:36:14 INFO - PROCESS | 24962 | 23:36:14 INFO - PROCESS | 24962 | ------- Summary: start ------- 23:36:14 INFO - PROCESS | 24962 | Number of tests: 6 23:36:14 INFO - PROCESS | 24962 | 23:36:14 INFO - PROCESS | 24962 | [#0] dojo.html Cycles:165 Average:5296.69 Median:5512.86 stddev:597.47 (10.8%) stddev-sans-first:573.39 23:36:14 INFO - PROCESS | 24962 | Values: 3077.7 3082.1 3105.3 3110.2 3146.3 5260.3 5275.6 5298.7 5322.5 5338.9 5262.0 5304.5 5362.5 5385.0 5457.6 4714.3 4786.3 4788.9 4800.1 4962.9 4875.0 4904.2 4904.5 4924.0 4930.3 4804.4 4808.4 4860.3 4867.0 4930.2 4772.8 4775.1 4795.6 4866.5 4928.1 4786.6 4819.4 4825.8 4840.3 4855.0 5191.9 5285.6 5311.9 5353.1 5379.7 5763.6 5771.8 5798.2 5808.7 5818.5 5819.6 5836.6 5859.0 5873.8 5885.4 5222.8 5224.9 5264.8 5275.2 5452.2 5832.6 5833.4 5835.5 5837.4 5841.4 5539.1 5601.8 5602.5 5604.0 5607.3 5605.1 5638.7 5682.5 5684.3 5696.2 5697.4 5702.1 5706.0 5706.1 5746.0 5657.1 5702.9 5709.4 5716.1 5718.4 4250.3 4322.6 4326.1 4393.9 4401.1 3921.7 3942.5 3944.9 3945.1 3945.2 5332.5 5333.1 5335.8 5335.9 5349.4 5571.4 5642.9 5652.2 5658.4 5710.1 5325.3 5355.8 5357.0 5363.4 5376.8 4898.1 4906.0 4922.9 4938.8 4959.1 5711.5 5737.7 5751.0 5786.5 5874.9 5646.9 5647.5 5650.1 5653.0 5660.3 5785.2 5807.7 5810.5 5812.3 5816.0 5470.3 5638.4 5642.2 5665.5 5772.7 5757.2 5786.5 5794.8 5795.8 5798.0 5685.7 5693.2 5701.2 5701.5 5703.7 5466.2 5524.0 5541.7 5576.6 5628.5 5477.7 5536.9 5538.7 5538.8 5543.6 5449.0 5605.8 5661.2 5668.3 5675.1 5418.3 5469.9 5475.4 5512.9 5552.9 23:36:14 INFO - PROCESS | 24962 | 23:36:14 INFO - PROCESS | 24962 | [#1] ext.html Cycles:180 Average:58268.22 Median:39661.40 stddev:117911.97 (297.3%) stddev-sans-first:118163.13 23:36:14 INFO - PROCESS | 24962 | Values: 557.6 583.3 607.6 619.8 637.7 38631.2 39893.6 40212.4 40288.0 41678.2 40606.8 40884.8 40942.2 40968.8 40983.0 8902.2 9062.7 9062.8 9063.2 9069.5 8972.0 9064.5 9078.7 9080.4 9085.5 9117.6 9163.7 9170.7 9172.5 9181.0 9032.2 9066.0 9069.2 9111.6 9116.0 8956.0 9020.1 9027.1 9030.0 9034.8 39213.8 39594.2 39650.6 39658.8 39664.0 3191.3 3323.4 3363.6 3404.9 3565.5 29791.4 30148.1 30473.2 30831.8 31366.7 4261.2 4389.5 4438.6 4457.1 4494.3 333667.0 334431.0 336972.0 337293.0 338674.0 49393.5 49683.5 50267.7 50358.5 50408.5 48864.8 49565.3 49598.8 49780.8 50007.5 14726.3 15549.8 15562.2 15564.4 15606.3 12060.5 12121.8 12128.6 12133.8 12136.5 13363.0 13725.2 13785.7 13880.4 14036.5 5787.6 5866.2 5910.9 5938.6 6080.9 925.6 981.5 991.7 1035.4 1107.9 30725.7 31007.2 31016.7 31129.5 31183.0 991.4 1025.1 1047.4 1074.9 1095.9 15950.1 16018.8 16190.4 16340.0 16357.4 518447.0 519339.0 519996.0 520998.0 521904.0 52533.2 52774.0 52793.5 52814.7 52865.2 1171.8 1235.0 1286.5 1287.8 1301.8 29379.0 29860.4 30250.4 30277.2 30853.0 46402.5 47177.0 47226.0 47270.8 47347.0 453891.0 454056.0 455214.0 458733.0 459298.0 33189.3 36881.6 37946.0 38769.8 38776.6 42205.2 42263.8 42302.2 42975.8 43408.0 35694.5 37156.8 37587.9 37998.4 38998.0 37135.3 37209.4 37681.6 37695.2 37775.4 37021.6 37059.3 37282.0 37356.6 37424.8 32725.7 34607.1 34879.0 35357.6 35373.6 26081.2 26361.2 26416.5 26623.7 26862.5 23:36:14 INFO - PROCESS | 24962 | 23:36:14 INFO - PROCESS | 24962 | [#2] jquery.html Cycles:180 Average:29513.72 Median:44780.05 stddev:85948.18 (191.9%) stddev-sans-first:86161.40 23:36:14 INFO - PROCESS | 24962 | Values: 353.5 354.6 364.7 377.5 382.5 17487.6 18016.0 18333.4 18410.5 18432.5 16963.9 17078.1 17357.5 17419.2 17735.1 5153.5 5326.7 5331.1 5331.2 5334.1 5159.1 5213.1 5242.0 5256.2 5285.5 5246.1 5246.7 5255.4 5255.4 5257.4 5142.7 5206.9 5238.5 5260.3 5274.2 5100.4 5112.9 5116.1 5116.8 5121.9 14123.6 14353.3 21858.7 22087.3 22127.3 2281.1 2436.1 2543.0 2792.9 2955.3 9367.1 9653.3 9988.0 10015.6 10250.6 1904.7 1906.3 1937.7 1949.2 1957.4 78253.6 79308.6 79607.0 80436.6 82219.6 10214.8 10364.9 10570.7 10645.8 10693.5 14883.4 14897.5 14915.9 14919.2 14954.9 7604.3 7690.6 7810.2 7912.3 7928.1 7568.8 7669.7 7904.4 7908.2 7939.7 4347.1 4468.4 4544.6 4560.5 4564.5 1795.5 1863.5 1888.6 1896.5 1904.3 798.7 933.8 939.1 1010.2 1024.7 18480.2 19497.2 19515.7 19547.7 19551.4 942.5 949.4 969.7 979.3 1004.3 8903.9 10617.5 10917.6 10920.8 10930.3 519531.0 519678.0 521240.0 522973.0 525556.0 90430.1 91427.5 92063.0 92552.0 93938.5 938.8 951.4 960.5 974.8 975.6 16234.9 16381.3 17362.6 18177.9 18674.3 41634.4 44618.3 44717.3 44842.8 45051.0 56349.7 57447.0 58818.0 59303.0 59945.4 8166.5 8222.8 8236.3 8372.6 8381.7 8804.2 9433.1 12148.3 12196.2 12335.8 11147.2 11505.3 11880.6 12070.9 12080.1 11322.6 11328.3 11447.1 11769.5 12179.9 11377.7 11817.1 11909.3 11950.6 12075.8 8080.2 8286.2 8290.0 8308.1 8653.6 8964.6 10772.9 11020.5 11222.9 11732.9 23:36:14 INFO - PROCESS | 24962 | 23:36:14 INFO - PROCESS | 24962 | [#3] mootools.html Cycles:180 Average:5666.81 Median:4854.87 stddev:3313.46 (68.3%) stddev-sans-first:3305.78 23:36:14 INFO - PROCESS | 24962 | Values: 1204.5 1226.5 1256.8 1307.0 1532.3 8173.0 8546.9 8630.3 8826.3 8835.7 8617.1 8668.2 8714.2 8721.2 8731.8 2278.4 2295.4 2296.6 2303.3 2304.9 2264.1 2286.5 2310.0 2314.1 2316.3 2273.6 2295.1 2300.1 2318.6 2321.6 2243.6 2256.2 2259.2 2260.1 2260.5 2235.7 2238.9 2242.5 2245.7 2247.6 8538.9 8604.6 8726.4 8729.4 8750.6 4970.8 5180.7 5208.6 5214.1 5214.6 3198.5 3345.7 3400.5 3401.8 3434.3 1207.3 1220.1 1223.5 1242.4 1255.9 12281.4 12346.6 12414.1 12414.8 12419.1 4058.3 4228.0 4358.3 4400.1 4401.4 9419.5 9422.1 9422.8 9430.1 9451.1 5053.5 5056.6 5061.9 5062.3 5066.2 4169.7 4549.8 4692.2 4711.8 4735.3 3455.4 3545.5 3560.3 3631.6 3650.2 1110.5 1115.3 1139.2 1142.4 1148.5 724.5 781.7 868.3 870.3 904.2 5521.9 5633.2 5681.0 5718.0 5863.4 427.2 434.1 434.3 908.5 971.6 3992.6 4006.4 4033.0 4039.2 4077.7 10232.4 10916.8 11370.5 11398.9 11403.0 10673.4 11112.2 11150.3 11157.7 11160.5 7794.7 8021.6 8058.6 8097.9 8195.7 5525.9 5643.7 5719.9 5803.0 5932.1 9597.4 9878.4 9955.2 10215.9 10290.4 10054.9 10139.5 10311.1 10433.8 10534.1 6960.7 7025.2 7579.4 7867.1 8037.6 8466.3 8595.2 8601.6 8603.6 8607.1 7323.0 7454.7 7480.8 7572.9 7593.6 7400.1 7461.0 7640.8 7643.2 7727.6 6668.9 6775.1 6781.3 6791.9 6799.8 4849.6 4860.1 4919.9 4983.5 4986.9 4889.9 4944.9 5064.0 5097.7 5144.9 23:36:14 INFO - PROCESS | 24962 | 23:36:14 INFO - PROCESS | 24962 | [#4] prototype.html Cycles:180 Average:4512.96 Median:5099.09 stddev:1312.35 (25.7%) stddev-sans-first:1277.51 23:36:14 INFO - PROCESS | 24962 | Values: 307.6 315.5 324.9 326.3 327.8 5102.7 5131.7 5188.3 5200.8 5256.4 5130.9 5174.7 5180.3 5181.9 5198.7 4075.3 4083.5 4089.3 4093.7 4097.6 3985.6 4034.5 4073.5 4088.9 4096.2 4010.5 4015.2 4045.3 4053.3 4057.4 4038.2 4043.7 4044.5 4054.2 4057.0 3610.3 3622.9 3630.7 3632.4 3650.4 4905.4 5026.9 5034.3 5063.9 5095.4 4645.2 4655.5 4656.7 4660.4 4662.9 5347.4 5376.9 5381.7 5382.2 5390.8 3872.7 3935.4 3964.2 3970.8 3987.2 5298.4 5363.7 5564.8 5604.2 5609.8 3974.4 3983.4 3983.5 3984.8 3988.5 3849.7 3952.0 4015.5 4033.9 4046.9 4569.1 4608.5 4634.5 4645.6 4648.3 5423.3 5430.8 5437.2 5453.4 5456.6 1363.9 1407.0 1414.7 1428.0 1430.7 891.6 917.9 918.7 925.5 928.5 4660.6 4661.4 4677.1 4680.5 4692.3 4834.4 4880.8 4890.0 4893.8 4913.5 4663.9 4677.0 4691.5 4722.5 4736.9 2709.5 2939.7 2956.1 2959.7 2965.5 5727.8 5740.7 5743.1 5746.9 5754.5 5517.3 5640.3 5701.8 5708.5 5711.5 5665.7 5707.9 5714.7 5731.3 5734.6 5542.3 5559.4 5564.3 5566.2 5599.9 5249.7 5447.8 5481.4 5536.3 5563.5 5729.5 5739.8 5755.8 5757.2 5757.4 3831.6 3939.3 3944.5 3952.4 3963.5 5465.2 5492.0 5495.2 5499.1 5506.9 5358.3 5379.8 5406.9 5413.8 5424.0 5123.8 5286.7 5309.0 5352.4 5377.8 5286.6 5287.8 5298.0 5303.7 5307.6 5372.4 5380.7 5388.2 5389.0 5400.8 5074.0 5284.7 5297.1 5334.3 5366.5 23:36:14 INFO - PROCESS | 24962 | 23:36:14 INFO - PROCESS | 24962 | [#5] yui.html Cycles:180 Average:20608.14 Median:4569.92 stddev:29760.08 (651.2%) stddev-sans-first:29805.88 23:36:14 INFO - PROCESS | 24962 | Values: 662.4 687.9 706.8 721.9 759.0 15414.4 16485.0 16535.8 16600.7 16609.3 13246.7 13410.0 13410.2 13435.2 13439.0 575.7 709.2 739.2 763.8 769.1 712.6 715.5 720.8 722.3 724.7 777.7 782.2 782.2 783.2 783.5 574.3 776.0 777.3 778.3 778.7 557.9 571.0 571.8 573.8 573.9 14437.0 14439.6 14440.3 14488.2 14507.1 13687.5 14236.8 14644.7 14695.6 14700.2 13319.7 13551.4 13639.9 13663.9 14256.4 4327.8 4488.5 4547.1 4568.2 4571.6 78809.8 79032.0 79257.6 79848.0 79895.0 10605.2 10633.9 10906.9 10953.7 11264.5 20231.4 20256.5 20293.6 20442.3 20608.0 8116.6 8216.5 8391.1 8499.4 8558.7 4538.0 4596.3 4720.5 4777.9 4928.4 3417.2 3568.1 3571.2 3572.4 3576.8 561.3 570.1 584.0 586.3 587.9 588.4 599.8 609.4 656.6 663.3 9270.8 9556.3 9662.2 9751.9 9845.1 568.7 617.3 617.4 671.2 691.3 4276.2 4400.0 4528.6 4620.4 4777.8 109720.0 114541.4 118893.0 119131.0 123427.0 83613.0 100769.1 101047.0 101556.5 102686.0 75045.0 82072.6 83845.6 84435.0 84875.0 9149.5 9442.6 9726.7 9899.0 9916.6 45807.0 50296.5 50355.0 50659.2 52098.2 59969.7 71963.3 72045.3 73342.6 73824.6 12531.9 12555.4 12744.7 13100.2 13565.3 14787.8 15059.8 15064.5 15080.7 15248.5 13034.6 13107.1 13108.5 13151.3 13471.2 13918.0 14274.6 14786.0 14787.0 15253.6 13011.4 13425.7 13502.5 13741.7 13895.2 10880.6 10892.6 10904.9 10930.6 11332.5 9968.9 10265.9 10453.4 11142.4 11452.7 23:36:14 INFO - PROCESS | 24962 | -------- Summary: end -------- 23:36:14 INFO - PROCESS | 24962 | 23:36:15 INFO - TEST-INFO | 24962: exit 0 23:36:15 INFO - TEST-OK | dromaeo_css | took 1114940ms 23:36:15 INFO - TEST-START | kraken 23:36:15 INFO - operating with platform_type : linux_ 23:36:15 INFO - Initialising browser for kraken test... 23:36:15 INFO - TEST-INFO | started process 25156 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4u3Ukg/profile http://localhost:44379/getInfo.html) 23:36:19 INFO - PROCESS | 25156 | __metrics Screen width/height:1600/1200 23:36:19 INFO - PROCESS | 25156 | colorDepth:24 23:36:19 INFO - PROCESS | 25156 | Browser inner width/height: 1024/697 23:36:19 INFO - PROCESS | 25156 | __metrics 23:36:20 INFO - PROCESS | 25156 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:36:20 INFO - PROCESS | 25156 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:36:21 INFO - TEST-INFO | 25156: exit 0 23:36:21 INFO - Browser initialized. 23:36:21 INFO - Running cycle 1/1 for kraken test... 23:36:21 INFO - TEST-INFO | started process 25235 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4u3Ukg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:36:32 INFO - PROCESS | 25235 | RSS: Main: 156065792 23:36:32 INFO - PROCESS | 25235 | 23:36:35 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44379/tests/kraken/driver.html?testName=audio-beat-detection) 23:36:36 INFO - PROCESS | 25235 | RSS: Main: 212639744 23:36:36 INFO - PROCESS | 25235 | 23:36:38 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44379/tests/kraken/driver.html?testName=audio-dft) 23:36:39 INFO - PROCESS | 25235 | RSS: Main: 191410176 23:36:39 INFO - PROCESS | 25235 | 23:36:42 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44379/tests/kraken/driver.html?testName=audio-fft) 23:36:43 INFO - PROCESS | 25235 | RSS: Main: 268296192 23:36:43 INFO - PROCESS | 25235 | 23:36:45 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44379/tests/kraken/driver.html?testName=audio-oscillator) 23:36:45 INFO - PROCESS | 25235 | RSS: Main: 178954240 23:36:45 INFO - PROCESS | 25235 | 23:36:48 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44379/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:36:48 INFO - PROCESS | 25235 | RSS: Main: 183791616 23:36:48 INFO - PROCESS | 25235 | 23:36:51 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44379/tests/kraken/driver.html?testName=imaging-darkroom) 23:36:52 INFO - PROCESS | 25235 | RSS: Main: 218681344 23:36:52 INFO - PROCESS | 25235 | 23:36:55 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44379/tests/kraken/driver.html?testName=imaging-desaturate) 23:36:56 INFO - PROCESS | 25235 | RSS: Main: 219164672 23:36:56 INFO - PROCESS | 25235 | 23:36:59 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44379/tests/kraken/driver.html?testName=json-parse-financial) 23:36:59 INFO - PROCESS | 25235 | RSS: Main: 219340800 23:36:59 INFO - PROCESS | 25235 | 23:37:01 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44379/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:37:01 INFO - PROCESS | 25235 | RSS: Main: 184057856 23:37:01 INFO - PROCESS | 25235 | 23:37:03 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44379/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:37:04 INFO - PROCESS | 25235 | RSS: Main: 188825600 23:37:04 INFO - PROCESS | 25235 | 23:37:06 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44379/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:37:06 INFO - PROCESS | 25235 | RSS: Main: 194244608 23:37:06 INFO - PROCESS | 25235 | 23:37:09 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44379/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:37:09 INFO - PROCESS | 25235 | RSS: Main: 187723776 23:37:09 INFO - PROCESS | 25235 | 23:37:12 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44379/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:37:13 INFO - PROCESS | 25235 | RSS: Main: 192495616 23:37:13 INFO - PROCESS | 25235 | 23:37:14 INFO - PROCESS | 25235 | Cycle 1(1): loaded http://localhost:44379/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44379/tests/kraken/driver.html?testName=ai-astar) 23:37:15 INFO - PROCESS | 25235 | RSS: Main: 243019776 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | __start_tp_report 23:37:15 INFO - PROCESS | 25235 | _x_x_mozilla_page_load 23:37:15 INFO - PROCESS | 25235 | _x_x_mozilla_page_load_details 23:37:15 INFO - PROCESS | 25235 | |i|pagename|runs| 23:37:15 INFO - PROCESS | 25235 | |0;ai-astar;102;112;111;109;110;112;110;109;112;111 23:37:15 INFO - PROCESS | 25235 | |1;audio-beat-detection;149;119;119;186;118;120;145;122;163;117 23:37:15 INFO - PROCESS | 25235 | |2;audio-dft;162;162;161;160;159;160;160;163;161;162 23:37:15 INFO - PROCESS | 25235 | |3;audio-fft;74;75;75;126;74;76;73;73;98;74 23:37:15 INFO - PROCESS | 25235 | |4;audio-oscillator;83;84;121;85;84;83;121;83;84;123 23:37:15 INFO - PROCESS | 25235 | |5;imaging-gaussian-blur;116;116;117;116;118;117;117;117;117;117 23:37:15 INFO - PROCESS | 25235 | |6;imaging-darkroom;146;146;146;146;146;147;146;146;145;146 23:37:15 INFO - PROCESS | 25235 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;94 23:37:15 INFO - PROCESS | 25235 | |8;json-parse-financial;54;54;52;52;53;53;53;52;51;54 23:37:15 INFO - PROCESS | 25235 | |9;json-stringify-tinderbox;60;62;61;61;61;60;61;61;62;61 23:37:15 INFO - PROCESS | 25235 | |10;stanford-crypto-aes;75;76;75;75;76;76;76;75;79;75 23:37:15 INFO - PROCESS | 25235 | |11;stanford-crypto-ccm;124;119;129;127;127;130;119;128;163;130 23:37:15 INFO - PROCESS | 25235 | |12;stanford-crypto-pbkdf2;164;166;157;167;160;160;157;159;158;155 23:37:15 INFO - PROCESS | 25235 | |13;stanford-crypto-sha256-iterative;66;62;66;63;64;66;62;63;63;65 23:37:15 INFO - PROCESS | 25235 | __end_tp_report 23:37:15 INFO - PROCESS | 25235 | __start_cc_report 23:37:15 INFO - PROCESS | 25235 | _x_x_mozilla_cycle_collect,1634 23:37:15 INFO - PROCESS | 25235 | __end_cc_report 23:37:15 INFO - PROCESS | 25235 | __startTimestamp1470119835186__endTimestamp 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | ------- Summary: start ------- 23:37:15 INFO - PROCESS | 25235 | Number of tests: 14 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#0] ai-astar Cycles:10 Average:109.80 Median:111.00 stddev:2.97 (2.7%) stddev-sans-first:1.22 23:37:15 INFO - PROCESS | 25235 | Values: 102.0 112.0 111.0 109.0 110.0 112.0 110.0 109.0 112.0 111.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:133.50 stddev:24.02 (18.0%) stddev-sans-first:25.00 23:37:15 INFO - PROCESS | 25235 | Values: 149.0 119.0 119.0 186.0 118.0 120.0 145.0 122.0 163.0 117.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.25 (0.8%) stddev-sans-first:1.27 23:37:15 INFO - PROCESS | 25235 | Values: 162.0 162.0 161.0 160.0 159.0 160.0 160.0 163.0 161.0 162.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#3] audio-fft Cycles:10 Average:81.80 Median:74.50 stddev:17.25 (23.2%) stddev-sans-first:18.07 23:37:15 INFO - PROCESS | 25235 | Values: 74.0 75.0 75.0 126.0 74.0 76.0 73.0 73.0 98.0 74.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:83.50 stddev:18.35 (22.0%) stddev-sans-first:18.93 23:37:15 INFO - PROCESS | 25235 | Values: 83.0 84.0 121.0 85.0 84.0 83.0 121.0 83.0 84.0 123.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 23:37:15 INFO - PROCESS | 25235 | Values: 116.0 116.0 117.0 116.0 118.0 117.0 117.0 117.0 117.0 117.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 23:37:15 INFO - PROCESS | 25235 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 145.0 146.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 23:37:15 INFO - PROCESS | 25235 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.03 (1.9%) stddev-sans-first:1.00 23:37:15 INFO - PROCESS | 25235 | Values: 54.0 54.0 52.0 52.0 53.0 53.0 53.0 52.0 51.0 54.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:61.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 23:37:15 INFO - PROCESS | 25235 | Values: 60.0 62.0 61.0 61.0 61.0 60.0 61.0 61.0 62.0 61.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.00 stddev:1.23 (1.6%) stddev-sans-first:1.27 23:37:15 INFO - PROCESS | 25235 | Values: 75.0 76.0 75.0 75.0 76.0 76.0 76.0 75.0 79.0 75.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#11] stanford-crypto-ccm Cycles:10 Average:129.60 Median:128.50 stddev:12.42 (9.7%) stddev-sans-first:13.01 23:37:15 INFO - PROCESS | 25235 | Values: 124.0 119.0 129.0 127.0 127.0 130.0 119.0 128.0 163.0 130.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.30 Median:160.00 stddev:4.06 (2.5%) stddev-sans-first:4.08 23:37:15 INFO - PROCESS | 25235 | Values: 164.0 166.0 157.0 167.0 160.0 160.0 157.0 159.0 158.0 155.0 23:37:15 INFO - PROCESS | 25235 | 23:37:15 INFO - PROCESS | 25235 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.50 stddev:1.63 (2.5%) stddev-sans-first:1.56 23:37:15 INFO - PROCESS | 25235 | Values: 66.0 62.0 66.0 63.0 64.0 66.0 62.0 63.0 63.0 65.0 23:37:15 INFO - PROCESS | 25235 | -------- Summary: end -------- 23:37:15 INFO - PROCESS | 25235 | 23:37:16 INFO - TEST-INFO | 25235: exit 0 23:37:16 INFO - TEST-OK | kraken | took 61121ms 23:37:16 INFO - SUITE-END | took 1176s 23:37:16 INFO - Completed test suite (00:19:36) 23:37:16 INFO - javascript benchmark 23:37:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3077.707617776312, 3082.121595345326, 3105.347876945841, 3110.237991692036, 3146.2606287522444, 5260.342457192851, 5275.630705850592, 5298.708571028592, 5322.49436303551, 5338.893222135564, 5262.0265250928505, 5304.522592966632, 5362.544183744377, 5384.973075134619, 5457.645253058548, 4714.3164241185, 4786.2581299898575, 4788.874614465615, 4800.135975524404, 4962.925556116661, 4875.024995000997, 4904.2398428243505, 4904.48502907195, 4923.990581930696, 4930.260460930853, 4804.447488538793, 4808.422989241296, 4860.270959356089, 4867.026594681078, 4930.235813448903, 4772.76136193189, 4775.092732380836, 4795.6163506919365, 4866.537678920497, 4928.137575924235, 4786.641001924835, 4819.445763737145, 4825.782839772219, 4840.298156767249, 4854.975725121387, 5191.922121168185, 5285.603579731497, 5311.920321195185, 5353.116735738598, 5379.65032272901, 5763.567732420043, 5771.797987070474, 5798.217240672498, 5808.7095645218, 5818.476337129637, 5819.621724587888, 5836.5622578306375, 5858.970705146447, 5873.765049398019, 5885.382034886361, 5222.817201397933, 5224.921626175573, 5264.78940842362, 5275.155975043975, 5452.2094296327205, 5832.562557808182, 5833.38749431312, 5835.533157347402, 5837.416258374214, 5841.4450627190345, 5539.086050801573, 5601.831945041656, 5602.4957753802355, 5603.971980140154, 5607.299087614048, 5605.131204663284, 5638.746256418471, 5682.545396368363, 5684.34630017545, 5696.202531645573, 5697.401772813795, 5702.087665973424, 5706.029974904276, 5706.058500347396, 5745.971270143623, 5657.12314591239, 5702.914456283159, 5709.400512946164, 5716.056850619684, 5718.428157184248, 4250.298700714409, 4322.632576231055, 4326.134773045404, 4393.934090988638, 4401.119776044739, 3921.745086569421, 3942.4677668514382, 3944.9408258876138, 3945.0926286954164, 3945.230680017369, 5332.466753324637, 5333.146696528537, 5335.786568537293, 5335.893282134336, 5349.438308977579, 5571.415001424792, 5642.887142257214, 5652.236948011966, 5658.377578466366, 5710.057840456361, 5325.334333208349, 5355.839324820262, 5357.0, 5363.356397232357, 5376.7580458878665, 4898.069366820293, 4905.9754701226975, 4922.852314430573, 4938.802447902043, 4959.057779021974, 5711.514521265739, 5737.655740655574, 5751.0, 5786.508146807484, 5874.9118763218585, 5646.858828529155, 5647.5481961442165, 5650.124230744241, 5653.0, 5660.349059858005, 5785.247917770663, 5807.651540907559, 5810.477057064884, 5812.273465816773, 5815.982203114353, 5470.343558772973, 5638.351589567253, 5642.153676948491, 5665.49010589049, 5772.740226689725, 5757.222774925332, 5786.450287222707, 5794.797182098607, 5795.82612521625, 5797.971010144923, 5685.71571421419, 5693.174489698888, 5701.173329867229, 5701.515371193329, 5703.7433315500075, 5466.234727138283, 5523.972380138073, 5541.695206763667, 5576.5817563683695, 5628.521575666115, 5477.67133971963, 5536.944630553804, 5538.723063846872, 5538.833834984957, 5543.6396634218645, 5449.0, 5605.80379686709, 5661.179129026351, 5668.291463567054, 5675.091985282335, 5418.322709661293, 5469.9179512306555, 5475.397706252389, 5512.862178445411, 5552.916706249329], "value": 5256.725310784963, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.6091420840279, 583.2534356024289, 607.6354187487507, 619.7892716476397, 637.7289651897942, 38631.22737545247, 39893.60106398935, 40212.39681404781, 40288.0, 41678.16643667125, 40606.7969660152, 40884.79557602215, 40942.18115637693, 40968.79515602425, 40983.0, 8902.2433093187, 9062.682806101788, 9062.773430664252, 9063.184313411792, 9069.455832650063, 8972.0, 9064.45613263203, 9078.682246121387, 9080.40977336471, 9085.454872707625, 9117.63529458824, 9163.679271225508, 9170.679026234084, 9172.495512746797, 9180.954095229514, 9032.232260257886, 9065.954670226673, 9069.229115525188, 9111.635534578609, 9115.954420227923, 8956.0, 9020.14308640678, 9027.14242146995, 9029.954850225773, 9034.819303613956, 39213.80393098016, 39594.20811583752, 39650.603493964976, 39658.80170599157, 39664.0, 3191.3457741163006, 3323.3685599735973, 3363.630000699921, 3404.914877128067, 3565.4830049642915, 29791.404171916656, 30148.09555713332, 30473.23816904595, 30831.84584077088, 31366.686333136604, 4261.2329780639475, 4389.473263208403, 4438.556144385568, 4457.130859482402, 4494.258447356182, 333666.99999999756, 334431.0, 336972.0, 337293.0, 338674.0, 49393.50606493961, 49683.50316496861, 50267.74866125646, 50358.49641503611, 50408.495915041116, 48864.75567622139, 49565.25652115149, 49598.75200624046, 49780.75109624429, 50007.499925001015, 14726.263686815832, 15549.844501555066, 15562.22188890551, 15564.377424903103, 15606.29771660278, 12060.51757929673, 12121.757564848655, 12128.636140915787, 12133.757324853454, 12136.514539418324, 13363.0, 13725.245111518961, 13785.655358616115, 13880.4447822088, 14036.508722194674, 5787.623804452697, 5866.208061911672, 5910.8817823643285, 5938.61399009063, 6080.878382432326, 925.6066171876926, 981.5435822342645, 991.7371896447447, 1035.414990530348, 1107.9224454288283, 30725.69274307273, 31007.224819379244, 31016.68983310183, 31129.533057004162, 31183.0, 991.4249735153594, 1025.1286406554368, 1047.3872784421199, 1074.8602681651334, 1095.9397233152254, 15950.122743249001, 16018.75971860423, 16190.376600289957, 16340.0, 16357.427490037791, 518447.0, 519339.0, 519996.0, 520998.0, 521904.0, 52533.21200182, 52774.0, 52793.472065279624, 52814.73592632089, 52865.20702189393, 1171.8183681529376, 1234.9876501234874, 1286.5304163980268, 1287.7617640736494, 1301.8242537257538, 29379.0, 29860.402791944474, 30250.394992100475, 30277.243068923453, 30853.0, 46402.535974639824, 47177.0, 47226.0, 47270.76364618155, 47347.0, 453891.0, 454056.0, 455214.0, 458733.0, 459298.0, 33189.33621327608, 36881.63118368782, 37946.0, 38769.80615096907, 38776.6122338773, 42205.155896882505, 42263.7886810564, 42302.153956920076, 42975.785121074194, 43408.0, 35694.464583030756, 37156.814215928745, 37587.8719374954, 37998.43002355022, 38998.0, 37135.257294853414, 37209.4418583716, 37681.623183768905, 37695.2460950774, 37775.433368500046, 37021.62978370182, 37059.25881482409, 37282.0, 37356.626433736405, 37424.812875935444, 32725.672743273215, 34607.13482162966, 34879.0, 35357.64642353646, 35373.64626353703, 26081.217563472368, 26361.209163725118, 26416.471670566098, 26623.733762662127, 26862.462750745268], "value": 19069.23016524559, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.46273664030656, 354.61524246192886, 364.67361711268416, 377.5092379906121, 382.4550016226876, 17487.562810929718, 18016.0, 18333.44999650011, 18410.539736506562, 18432.539186520342, 16963.91518042408, 17078.146092695377, 17357.47927562172, 17419.216135273913, 17735.113244337765, 5153.536181743642, 5326.7070311132875, 5331.147016477365, 5331.2269720890445, 5334.093204155283, 5159.1229490986625, 5213.113770658997, 5241.973790131044, 5256.185291279858, 5285.524302812746, 5246.055709972204, 5246.685198888079, 5255.421903590602, 5255.448177941318, 5257.36911570612, 5142.74286285688, 5206.947930520703, 5238.476152384769, 5260.342457192832, 5274.182501712241, 5100.438951715309, 5112.923306150411, 5116.130257856136, 5116.846494605168, 5121.897562048775, 14123.646908827259, 14353.282335883165, 21858.672119918214, 22087.33737987863, 22127.33617991463, 2281.133169395636, 2436.098643501898, 2543.0, 2792.9162125136277, 2955.3202763364548, 9367.063293670646, 9653.324267301292, 9987.950060249725, 10015.59937602495, 10250.641227557075, 1904.7047707605343, 1906.2946709717355, 1937.728717979484, 1949.1716020691292, 1957.3736404350614, 78253.60873195597, 79308.60345698292, 79607.0, 80436.59781701054, 82219.58890205571, 10214.803817179341, 10364.896351036545, 10570.682879513626, 10645.84031239532, 10693.518791654238, 14883.404663813542, 14897.47858824936, 14915.850841491663, 14919.179445130405, 14954.850451495564, 7604.315611594872, 7690.615469226516, 7810.1799311072655, 7912.32745216663, 7928.088269849041, 7568.8486230276185, 7669.6548655309525, 7904.367650587939, 7908.169642187602, 7939.722109726132, 4347.130573885235, 4468.419105516262, 4544.636429085665, 4560.452745670541, 4564.497905230422, 1795.4882858385558, 1863.543431859203, 1888.5845114075157, 1896.4784684211952, 1904.3144467991513, 798.7084714079435, 933.8365785987424, 939.0656296984544, 1010.1666125446536, 1024.7130803375067, 18480.168392422373, 19497.22011119568, 19515.707264391047, 19547.706784398244, 19551.413457596296, 942.4533770413144, 949.3734135470556, 969.7284760267137, 979.3487330924997, 1004.2969921055145, 8903.910960890309, 10617.469126543796, 10917.56329746817, 10920.836187457195, 10930.289531180448, 519531.0, 519678.0, 521240.0, 522973.0, 525556.0, 90430.09569904348, 91427.54286228526, 92063.0, 92552.0, 93938.5303073494, 938.8075444533854, 951.3911096897991, 960.524540352516, 974.8294048541619, 975.6390135649855, 16234.918825405797, 16381.344746210018, 17362.565935851704, 18177.909110454362, 18674.253029879197, 41634.375484367156, 44618.3307250398, 44717.32924006208, 44842.77578612086, 45051.0, 56349.71825141012, 57447.0, 58818.0, 59303.0, 59945.40054599398, 8166.469179503313, 8222.84612387203, 8236.299914507334, 8372.581370931552, 8381.706640267561, 8804.196467341266, 9433.103855133751, 12148.331841748788, 12196.207246528948, 12335.814962775386, 11147.163962702667, 11505.309681419141, 11880.584179553674, 12070.939645301718, 12080.093992950387, 11322.60370887015, 11328.32030078198, 11447.14146439037, 11769.47037383337, 12179.878201217874, 11377.715557111138, 11817.113716471471, 11909.285442873455, 11950.641480755592, 12075.818862716891, 8080.1919808021075, 8286.171382861665, 8289.95855020721, 8308.127646597262, 8653.61058752345, 8964.641414343354, 10772.946135269587, 11020.50407731638, 11222.943885280521, 11732.94133529327], "value": 8871.678721767146, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1204.470033185398, 1226.4603574427251, 1256.7800634888893, 1306.9609660320045, 1532.3410933298678, 8172.9591352043235, 8546.931633545806, 8630.266427353667, 8826.338024648161, 8835.734927952171, 8617.13828617138, 8668.176523230299, 8714.17215364541, 8721.215090641841, 8731.781705457366, 2278.3848360942493, 2295.437617783637, 2296.5866144094057, 2303.320520446464, 2304.9193278235266, 2264.1283106004225, 2286.4855407533337, 2310.0, 2314.1090680088205, 2316.2819525946925, 2273.59075366434, 2295.070496448941, 2300.1374484568287, 2318.5710643531, 2321.616933206019, 2243.596152692515, 2256.2103263857603, 2259.186692790594, 2260.107257633242, 2260.49138943738, 2235.709357783494, 2238.8880555972137, 2242.461809165805, 2245.674377215311, 2247.5617254635354, 8538.87191692125, 8604.612792424294, 8726.432781869158, 8729.432586881832, 8750.562471876445, 4970.801167953277, 5180.715060671661, 5208.583313334924, 5214.139666954948, 5214.556762675177, 3198.5042318440446, 3345.7156141727983, 3400.4559270516847, 3401.812900290483, 3434.3131373725355, 1207.3419986107592, 1220.0971281251898, 1223.4922507159517, 1242.3974372429363, 1255.9309237991906, 12281.447334869952, 12346.629601111981, 12414.131010829245, 12414.75170496585, 12419.130660853654, 4058.2695114879016, 4227.978860105742, 4358.280883654225, 4400.075984043387, 4401.361802538678, 9419.481928493999, 9422.057794220524, 9422.764430889283, 9430.10414010668, 9451.054894510631, 5053.545180933734, 5056.646034777604, 5061.924071138936, 5062.341895553554, 5066.240063990357, 4169.708120431541, 4549.772511374418, 4692.202325604587, 4711.764411779466, 4735.289706543977, 3455.395305821471, 3545.4681797730545, 3560.287942411528, 3631.5823680276576, 3650.1604630935035, 1110.4836251143095, 1115.2583531951325, 1139.2196345503191, 1142.440204299887, 1148.534843388429, 724.5109551053018, 781.7185813107277, 868.3140319147802, 870.3298460185622, 904.1771987491351, 5521.944780552223, 5633.183188437653, 5680.971595141998, 5717.971410142923, 5863.413658634187, 427.20753003179277, 434.0624251616556, 434.2834323366471, 908.5003248213457, 971.6494073238205, 3992.640662340403, 4006.419069234945, 4032.959670403317, 4039.171969746195, 4077.6941729369823, 10232.437215953194, 10916.781664366827, 11370.488328025258, 11398.886011139948, 11403.03074238699, 10673.35959842412, 11112.16658750597, 11150.330980141218, 11157.721056973478, 11160.497777600027, 7794.727184548514, 8021.639026243833, 8058.637361318754, 8097.919020809716, 8195.67217311301, 5525.917111243254, 5643.717814109196, 5719.942800571941, 5802.970985145047, 5932.085102988864, 9597.376170548892, 9878.357906736039, 9955.20358371314, 10215.897841021495, 10290.3825770454, 10054.949725251327, 10139.493025348851, 10311.123554497952, 10433.791324173322, 10534.051935325857, 6960.686769095504, 7025.156981162293, 7579.393648507996, 7867.09528404218, 8037.598120093855, 8466.322694184328, 8595.18345757152, 8601.569921504026, 8603.569821509025, 8607.096254893146, 7322.96338518304, 7454.701811927463, 7480.812979675552, 7572.924270757222, 7593.5823529706395, 7400.111986561647, 7461.0, 7640.8471830564185, 7643.197464266172, 7727.574983375968, 6668.933310666831, 6775.119234499681, 6781.254062053072, 6791.864162716817, 6799.7620083298825, 4849.612031037579, 4860.128850392553, 4919.926201106915, 4983.501649834988, 4986.850394488171, 4889.90220195587, 4944.851654450372, 5064.0, 5097.745112744422, 5144.897102057863], "value": 4450.85416200945, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.5678671466589, 315.51095801507665, 324.88628979857043, 326.3391631945309, 327.7787493441926, 5102.744862756866, 5131.666441681288, 5188.273641690166, 5200.765965531551, 5256.395514515832, 5130.897382052357, 5174.715390653512, 5180.274761533388, 5181.922271165936, 5198.740062996854, 4075.3071977763702, 4083.5099788025477, 4089.3457046872504, 4093.713440059182, 4097.631213190813, 3985.6448807405477, 4034.5158580970324, 4073.450084238634, 4088.8568900088503, 4096.221717873595, 4010.498687664042, 4015.176888737816, 4045.2920738870725, 4053.3312003519386, 4057.3913912913154, 4038.212548553004, 4043.65628921542, 4044.534878488982, 4054.1688953764406, 4056.9594304057023, 3610.295992281506, 3622.945655815165, 3630.709543236535, 3632.436972269276, 3650.361186792301, 4905.447670735402, 5026.899462010776, 5034.320366750479, 5063.949360506384, 5095.439501654815, 4645.163870503308, 4655.534446555352, 4656.65075119364, 4660.39414876064, 4662.883427914296, 5347.385050719139, 5376.865578360533, 5381.650192737459, 5382.219578161184, 5390.811321603725, 3872.7482713623526, 3935.4293627423863, 3964.1873415949663, 3970.8411663533425, 3987.1626958338493, 5298.377303234924, 5363.704996225167, 5564.833055008316, 5604.159376093618, 5609.775608975636, 3974.423708562242, 3983.402489626579, 3983.50206224222, 3984.820683069269, 3988.5470435681846, 3849.6517124554, 3951.9604803952166, 4015.4980627421573, 4033.9193216135513, 4046.858359957387, 4569.103821913935, 4608.456167184031, 4634.536546345406, 4645.581897629229, 4648.302754586772, 5423.322084739408, 5430.755615997211, 5437.184422336682, 5453.372862120827, 5456.563474921997, 1363.9454421823016, 1407.0, 1414.731201071793, 1427.9500217492546, 1430.7210094031564, 891.6477991193572, 917.8990311065777, 918.6509126532009, 925.4863550729275, 928.5496534180917, 4660.580547750718, 4661.417322834645, 4677.082396217603, 4680.531946805292, 4692.272697731857, 4834.371531700856, 4880.780364883589, 4889.951100489021, 4893.828715994923, 4913.459519452857, 4663.924553573627, 4676.972254826075, 4691.483936766953, 4722.480527142012, 4736.905261894743, 2709.4716530276405, 2939.6913324101088, 2956.054062699958, 2959.718826711459, 2965.54034124711, 5727.799527016535, 5740.65556066629, 5743.10981797822, 5746.885062298814, 5754.510866576305, 5517.282753242133, 5640.32316122068, 5701.8289451315695, 5708.543316534668, 5711.45741154598, 5665.745041473144, 5707.885842283048, 5714.685692286964, 5731.312242530923, 5734.598578099576, 5542.251796007487, 5559.360673522597, 5564.304461942257, 5566.192902029264, 5599.900037485942, 5249.685018898878, 5447.782088716565, 5481.369642491165, 5536.339098270583, 5563.5271001963665, 5729.4843464088435, 5739.770409183586, 5755.798547050833, 5757.222774925332, 5757.445489717669, 3831.5593706724085, 3939.330313846596, 3944.5069366329208, 3952.4268980998168, 3963.4649322341115, 5465.207544905887, 5491.9725401374335, 5495.230667706604, 5499.065158923073, 5506.889862202814, 5358.303420555459, 5379.838604841861, 5406.918896216638, 5413.810516631899, 5423.972880135732, 5123.769430375568, 5286.7356632169, 5309.017831701148, 5352.384475785178, 5377.758000889891, 5286.62993590445, 5287.788488460418, 5298.0, 5303.655262407931, 5307.601929855199, 5372.435894231049, 5380.730963451733, 5388.191771234347, 5389.0, 5400.783968641211, 5074.0, 5284.735763211747, 5297.073012222922, 5334.306540149756, 5366.463353664602], "value": 4095.8394531455388, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.3707477896, 687.8830598798204, 706.8197609609549, 721.8808896532072, 758.9715934908199, 15414.383424663014, 16485.0, 16535.751963720522, 16600.66798664026, 16609.33562657494, 13246.735065298688, 13410.0, 13410.195388276687, 13435.19388836668, 13439.0, 575.680497323985, 709.2269426325305, 739.2238149942566, 763.7708687393788, 769.1424062170695, 712.5653351455608, 715.5027256057017, 720.8378114924152, 722.2596838240819, 724.69562783631, 777.7122464688073, 782.194339829976, 782.2060608482367, 783.1737516919648, 783.475071701961, 574.2649408756798, 775.9883601745922, 777.3120788102542, 778.2878666020584, 778.6963084397062, 557.8967890940148, 571.0263999880194, 571.8227349521661, 573.7676241122352, 573.8909607174623, 14437.0, 14439.639009024753, 14440.277986100653, 14488.20314882681, 14507.057041292283, 13687.452501899912, 14236.786448203286, 14644.70710585793, 14695.559133226021, 14700.191489468076, 13319.733605327936, 13551.39018744159, 13639.863601363959, 13663.863361366459, 14256.429742810275, 4327.805248763813, 4488.483824360175, 4547.067851090552, 4568.2462393705, 4571.588557029883, 78809.81785273283, 79032.0, 79257.60371198106, 79848.0, 79895.0, 10605.151587872951, 10633.893661063445, 10906.945465272782, 10953.726156846142, 11264.54941802335, 20231.393058208276, 20256.493587660425, 20293.594128117355, 20442.342495475492, 20608.0, 8116.5941702914615, 8216.548089855116, 8391.118932512121, 8499.36254780894, 8558.700445484377, 4538.0, 4596.310553416947, 4720.504347043511, 4777.880552986203, 4928.408590969035, 3417.179876829543, 3568.072301201706, 3571.160777217352, 3572.4284114541547, 3576.803275819855, 561.284362437895, 570.0850135532411, 583.9649621022752, 586.2700937333047, 587.9059350503899, 588.3733823477983, 599.8050633544028, 609.3966972696998, 656.6421300391298, 663.3300366629738, 9270.814583708423, 9556.331056825955, 9662.227021838236, 9751.90248097524, 9845.113939745313, 568.7099579214548, 617.2654541096082, 617.3517806303364, 671.2280876991499, 691.2922994408096, 4276.2089013532595, 4399.978000109979, 4528.615067719281, 4620.3993480847275, 4777.784999675023, 109720.0, 114541.42729286467, 118893.0, 119131.0, 123427.0, 83613.0, 100769.05010568752, 101047.0, 101556.49221753991, 102686.0, 75045.0, 82072.58963705144, 83845.58077209575, 84435.0, 84875.0, 9149.451032938045, 9442.622295108393, 9726.70819875405, 9899.0, 9916.603335866484, 45807.0, 50296.497035029184, 50355.0, 50659.24011139762, 52098.218526722885, 59969.70015149896, 71963.28036719775, 72045.27954720595, 73342.63328683322, 73824.63087684527, 12531.93734031324, 12555.372231388212, 12744.6813829655, 13100.21398716042, 13565.253911034988, 14787.778183327473, 15059.849401505844, 15064.472743453927, 15080.698386032438, 15248.466303679317, 13034.57055336741, 13107.14803537767, 13108.541201057917, 13151.318972353503, 13471.191728495929, 13918.0, 14274.643133921734, 14786.0, 14787.0, 15253.6186595336, 13011.414486347947, 13425.66435839112, 13502.527411540941, 13741.725165496833, 13895.166290022631, 10880.619178329038, 10892.641375660281, 10904.890951090387, 10930.617428389969, 11332.490037948477, 9968.850467242852, 10265.948670256601, 10453.42506162138, 11142.387168705798, 11452.713682158013], "value": 7349.8424295990635, "unit": "score"}], "name": "dromaeo_css", "value": 7014.994854213835, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 111.0, 109.0, 110.0, 112.0, 110.0, 109.0, 112.0, 111.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 119.0, 119.0, 186.0, 118.0, 120.0, 145.0, 122.0, 163.0, 117.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 160.0, 159.0, 160.0, 160.0, 163.0, 161.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 126.0, 74.0, 76.0, 73.0, 73.0, 98.0, 74.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 121.0, 85.0, 84.0, 83.0, 121.0, 83.0, 84.0, 123.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 52.0, 52.0, 53.0, 53.0, 53.0, 52.0, 51.0, 54.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 61.0, 61.0, 61.0, 60.0, 61.0, 61.0, 62.0, 61.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 75.0, 76.0, 76.0, 76.0, 75.0, 79.0, 75.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 119.0, 129.0, 127.0, 127.0, 130.0, 119.0, 128.0, 163.0, 130.0], "value": 129.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 157.0, 167.0, 160.0, 160.0, 157.0, 159.0, 158.0, 155.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 66.0, 63.0, 64.0, 66.0, 62.0, 63.0, 63.0, 65.0], "value": 64.0, "unit": "score"}], "name": "kraken", "value": 1484.6999999999998, "alertThreshold": 2.0}]} 23:37:16 INFO - Return code: 0 23:37:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:37:16 INFO - # TBPL SUCCESS # 23:37:16 INFO - Running post-action listener: _resource_record_post_action 23:37:16 INFO - [mozharness: 2016-08-02 06:37:16.486726Z] Finished run-tests step (success) 23:37:16 INFO - Running post-run listener: _resource_record_post_run 23:37:16 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 546299904; Read time: 20; Write time: 772720 23:37:16 INFO - TinderboxPrint: CPU usage
13.1% 23:37:16 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 23:37:16 INFO - TinderboxPrint: I/O write bytes / time
546,299,904 / 772,720 23:37:16 INFO - TinderboxPrint: CPU idle
8,277.2 (86.9%) 23:37:16 INFO - TinderboxPrint: CPU user
1,194.1 (12.5%) 23:37:16 INFO - TinderboxPrint: Swap in / out
0 / 0 23:37:16 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 255553536; Read time: 0; Write time: 356044 23:37:16 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 290406400; Read time: 0; Write time: 416672 23:37:17 INFO - Running post-run listener: _upload_blobber_files 23:37:17 INFO - Blob upload gear active. 23:37:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:37:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:37:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:37:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:37:17 INFO - (blobuploader) - INFO - Open directory for files ... 23:37:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:37:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:37:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:18 INFO - (blobuploader) - INFO - Done attempting. 23:37:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:37:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:37:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:19 INFO - (blobuploader) - INFO - Done attempting. 23:37:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:37:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:37:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:37:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:37:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:37:23 INFO - (blobuploader) - INFO - Done attempting. 23:37:23 INFO - (blobuploader) - INFO - Iteration through files over. 23:37:23 INFO - Return code: 0 23:37:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:37:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:37:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87e947f6cf85b78898dbc639eb8a4156627eba8470d0441caa6d040f70788b96b1b3b0e5bc6586717da90ae66c912674364f0a6f14ec3254b6e91965b50dda2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f36a97a74218e1c02fcebd4c70bda0b02bf54da33d03d324730c24b00d1ecfce9e8b62fb86feee0e9f384cb9e0b67f71e5ac2eb6c4caa865d61e32c9e3ae5e83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47c0c19a7d50a7db097e241fe4f7e8ba3a23cc570c51050d2d98d3977aa70a4ab449279954783b80f06b455836fa096342d8507d48ed5daf5a0750338433c5a4"} 23:37:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:37:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:37:23 INFO - Contents: 23:37:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87e947f6cf85b78898dbc639eb8a4156627eba8470d0441caa6d040f70788b96b1b3b0e5bc6586717da90ae66c912674364f0a6f14ec3254b6e91965b50dda2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f36a97a74218e1c02fcebd4c70bda0b02bf54da33d03d324730c24b00d1ecfce9e8b62fb86feee0e9f384cb9e0b67f71e5ac2eb6c4caa865d61e32c9e3ae5e83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47c0c19a7d50a7db097e241fe4f7e8ba3a23cc570c51050d2d98d3977aa70a4ab449279954783b80f06b455836fa096342d8507d48ed5daf5a0750338433c5a4"} 23:37:23 INFO - Running post-run listener: copy_logs_to_upload_dir 23:37:23 INFO - Copying logs to upload dir... 23:37:23 INFO - mkdir: /builds/slave/test/build/upload/logs 23:37:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.303058 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-08-01 23:37:23.209551) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 23:37:23.212685) ========= 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=14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87e947f6cf85b78898dbc639eb8a4156627eba8470d0441caa6d040f70788b96b1b3b0e5bc6586717da90ae66c912674364f0a6f14ec3254b6e91965b50dda2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f36a97a74218e1c02fcebd4c70bda0b02bf54da33d03d324730c24b00d1ecfce9e8b62fb86feee0e9f384cb9e0b67f71e5ac2eb6c4caa865d61e32c9e3ae5e83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47c0c19a7d50a7db097e241fe4f7e8ba3a23cc570c51050d2d98d3977aa70a4ab449279954783b80f06b455836fa096342d8507d48ed5daf5a0750338433c5a4"} build_url:https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/JlOBYHRJTJ6s0liffplwSQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87e947f6cf85b78898dbc639eb8a4156627eba8470d0441caa6d040f70788b96b1b3b0e5bc6586717da90ae66c912674364f0a6f14ec3254b6e91965b50dda2f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f36a97a74218e1c02fcebd4c70bda0b02bf54da33d03d324730c24b00d1ecfce9e8b62fb86feee0e9f384cb9e0b67f71e5ac2eb6c4caa865d61e32c9e3ae5e83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47c0c19a7d50a7db097e241fe4f7e8ba3a23cc570c51050d2d98d3977aa70a4ab449279954783b80f06b455836fa096342d8507d48ed5daf5a0750338433c5a4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 23:37:23.250134) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 23:37:23.250577) ========= 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=14ef55db36f7623abb31b9cf000001d5-1470118520.287747-2142073633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004394 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 23:37:23.334563) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 23:37:23.335149) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 23:37:23.335495) ========= ========= Total master_lag: 0.20 =========