builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-042 starttime: 1470179940.35 results: success (0) buildid: 20160802161836 builduid: 80f4264ed3d84b9bb20e8d71dfc75bbe revision: 73a57814a495b29244ef5377e73488a3f3fabb15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.353144) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.353609) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.353892) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.412850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.413128) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-02 16:19:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-08-02 16:19:01 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075645 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.508583) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.508908) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016627 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.559461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.559816) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 73a57814a495b29244ef5377e73488a3f3fabb15 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 73a57814a495b29244ef5377e73488a3f3fabb15 --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=74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-02 16:19:01,130 truncating revision to first 12 chars 2016-08-02 16:19:01,130 Setting DEBUG logging. 2016-08-02 16:19:01,130 attempt 1/10 2016-08-02 16:19:01,130 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/73a57814a495?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-02 16:19:01,508 unpacking tar archive at: mozilla-inbound-73a57814a495/testing/mozharness/ program finished with exit code 0 elapsedTime=0.720424 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:01.295101) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:01.295517) ========= script_repo_revision: 73a57814a495b29244ef5377e73488a3f3fabb15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:01.296100) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:01.296438) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:01.307588) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-08-02 16:19:01.307942) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:19:01 INFO - MultiFileLogger online at 20160802 16:19:01 in /builds/slave/test 16:19:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:19:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:19:01 INFO - {'append_to_log': False, 16:19:01 INFO - 'base_work_dir': '/builds/slave/test', 16:19:01 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:19:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:19:01 INFO - 'branch': 'Mozilla-Inbound', 16:19:01 INFO - 'buildbot_json_path': 'buildprops.json', 16:19:01 INFO - 'config_files': ('talos/linux_config.py',), 16:19:01 INFO - 'default_actions': ('clobber', 16:19:01 INFO - 'read-buildbot-config', 16:19:01 INFO - 'download-and-extract', 16:19:01 INFO - 'populate-webroot', 16:19:01 INFO - 'create-virtualenv', 16:19:01 INFO - 'install', 16:19:01 INFO - 'run-tests'), 16:19:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:19:01 INFO - 'download_minidump_stackwalk': True, 16:19:01 INFO - 'download_symbols': 'ondemand', 16:19:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:19:01 INFO - 'tooltool.py': '/tools/tooltool.py', 16:19:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:19:01 INFO - '/tools/misc-python/virtualenv.py')}, 16:19:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:19:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:19:01 INFO - 'installer_path': 'installer.exe', 16:19:01 INFO - 'log_level': 'info', 16:19:01 INFO - 'log_name': 'talos', 16:19:01 INFO - 'log_to_console': True, 16:19:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:19:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:19:01 INFO - 'opt_config_files': (), 16:19:01 INFO - 'pip_index': False, 16:19:01 INFO - 'sps_profile': False, 16:19:01 INFO - 'sps_profile_interval': 0, 16:19:01 INFO - 'suite': 'dromaeojs', 16:19:01 INFO - 'system_bits': '32', 16:19:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:19:01 INFO - 'title': 'talos-linux64-ix-042', 16:19:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:19:01 INFO - 'use_talos_json': True, 16:19:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:19:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:19:01 INFO - 'work_dir': 'build'} 16:19:01 INFO - [mozharness: 2016-08-02 23:19:01.971693Z] Running clobber step. 16:19:01 INFO - Running pre-action listener: _resource_record_pre_action 16:19:01 INFO - Running main action method: clobber 16:19:01 INFO - rmtree: /builds/slave/test/build 16:19:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:19:02 INFO - Running post-action listener: _resource_record_post_action 16:19:02 INFO - [mozharness: 2016-08-02 23:19:02.951679Z] Finished clobber step (success) 16:19:02 INFO - [mozharness: 2016-08-02 23:19:02.951782Z] Running read-buildbot-config step. 16:19:02 INFO - Running pre-action listener: _resource_record_pre_action 16:19:02 INFO - Running main action method: read_buildbot_config 16:19:02 INFO - Using buildbot properties: 16:19:02 INFO - { 16:19:02 INFO - "project": "", 16:19:02 INFO - "product": "firefox", 16:19:02 INFO - "script_repo_revision": "production", 16:19:02 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:19:02 INFO - "repository": "", 16:19:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:19:02 INFO - "buildid": "20160802161836", 16:19:02 INFO - "stage_platform": "linux64-pgo", 16:19:02 INFO - "basedir": "/builds/slave/test", 16:19:02 INFO - "buildnumber": 1339, 16:19:02 INFO - "platform": "ubuntu64_hw", 16:19:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:19:02 INFO - "slavebuilddir": "test", 16:19:02 INFO - "branch": "mozilla-inbound", 16:19:02 INFO - "revision": "73a57814a495b29244ef5377e73488a3f3fabb15", 16:19:02 INFO - "slavename": "talos-linux64-ix-042", 16:19:02 INFO - "builduid": "80f4264ed3d84b9bb20e8d71dfc75bbe", 16:19:02 INFO - "repo_path": "integration/mozilla-inbound" 16:19:02 INFO - } 16:19:02 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:19:02 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 16:19:02 INFO - Running post-action listener: _resource_record_post_action 16:19:02 INFO - [mozharness: 2016-08-02 23:19:02.954147Z] Finished read-buildbot-config step (success) 16:19:02 INFO - [mozharness: 2016-08-02 23:19:02.954235Z] Running download-and-extract step. 16:19:02 INFO - Running pre-action listener: _resource_record_pre_action 16:19:02 INFO - Running main action method: download_and_extract 16:19:02 INFO - mkdir: /builds/slave/test/build/tests 16:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:19:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/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 16:19:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/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 16:19:02 INFO - Downloaded 1361 bytes. 16:19:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:19:02 INFO - Using the following test package requirements: 16:19:02 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:19:02 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:19:02 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:19:02 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'jsshell-linux-x86_64.zip'], 16:19:02 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:19:02 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:19:02 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:19:02 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:19:02 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:19:02 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:19:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:19:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/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 16:19:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/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 16:19:03 INFO - Downloaded 21811128 bytes. 16:19:03 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'] 16:19:03 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 16:19:04 INFO - Return code: 0 16:19:04 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 16:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:19:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/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 16:19:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/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 16:19:05 INFO - Downloaded 21811128 bytes. 16:19:05 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'] 16:19:05 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 16:19:06 INFO - Return code: 0 16:19: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')]} 16:19:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:19:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/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 16:19:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/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 16:19:06 INFO - Downloaded 14413723 bytes. 16:19:06 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'] 16:19:06 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 16:19:07 INFO - Return code: 0 16:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:07 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:19:07 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/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 16:19:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/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 16:19:08 INFO - Downloaded 59398441 bytes. 16:19:08 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:19:08 INFO - mkdir: /builds/slave/test/properties 16:19:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:19:08 INFO - Writing to file /builds/slave/test/properties/build_url 16:19:08 INFO - Contents: 16:19:08 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:19:08 INFO - Running post-action listener: _resource_record_post_action 16:19:08 INFO - Running post-action listener: set_extra_try_arguments 16:19:08 INFO - [mozharness: 2016-08-02 23:19:08.775115Z] Finished download-and-extract step (success) 16:19:08 INFO - [mozharness: 2016-08-02 23:19:08.775349Z] Running populate-webroot step. 16:19:08 INFO - Running pre-action listener: _resource_record_pre_action 16:19:08 INFO - Running main action method: populate_webroot 16:19:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:19:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:19:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:19:08 INFO - '--tppagecycles', 16:19:08 INFO - '7'], 16:19:08 INFO - 'tests': ['tsvgm']}}, 16:19:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:19:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:19:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:19:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:19:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:08 INFO - 'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:08 INFO - 'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['damp', 'tps']}, 16:19:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:08 INFO - 'tests': ['damp', 'tps']}, 16:19:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['dromaeo_dom']}, 16:19:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:19:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['basic_compositor_video']}, 16:19:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:19:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['a11yr', 16:19:08 INFO - 'ts_paint', 16:19:08 INFO - 'tpaint', 16:19:08 INFO - 'sessionrestore', 16:19:08 INFO - 'sessionrestore_no_auto_restore', 16:19:08 INFO - 'tabpaint']}, 16:19:08 INFO - 'other-e10s': {'tests': ['a11yr', 16:19:08 INFO - 'ts_paint', 16:19:08 INFO - 'tpaint', 16:19:08 INFO - 'sessionrestore', 16:19:08 INFO - 'sessionrestore_no_auto_restore', 16:19:08 INFO - 'tabpaint']}, 16:19:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:19:08 INFO - 'ts_paint', 16:19:08 INFO - 'tpaint', 16:19:08 INFO - 'sessionrestore', 16:19:08 INFO - 'sessionrestore_no_auto_restore', 16:19:08 INFO - 'tabpaint']}, 16:19:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:19:08 INFO - 'ts_paint', 16:19:08 INFO - 'tpaint', 16:19:08 INFO - 'sessionrestore', 16:19:08 INFO - 'sessionrestore_no_auto_restore', 16:19:08 INFO - 'tabpaint']}, 16:19:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['a11yr', 16:19:08 INFO - 'ts_paint', 16:19:08 INFO - 'tpaint', 16:19:08 INFO - 'sessionrestore', 16:19:08 INFO - 'sessionrestore_no_auto_restore', 16:19:08 INFO - 'tabpaint']}, 16:19:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['a11yr', 16:19:08 INFO - 'ts_paint', 16:19:08 INFO - 'tpaint', 16:19:08 INFO - 'sessionrestore', 16:19:08 INFO - 'sessionrestore_no_auto_restore', 16:19:08 INFO - 'tabpaint']}, 16:19:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['tsvgx', 16:19:08 INFO - 'tsvgr_opacity', 16:19:08 INFO - 'tart', 16:19:08 INFO - 'tscrollx', 16:19:08 INFO - 'cart']}, 16:19:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:19:08 INFO - 'tsvgr_opacity', 16:19:08 INFO - 'tart', 16:19:08 INFO - 'tscrollx', 16:19:08 INFO - 'cart']}, 16:19:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:08 INFO - 'talos_options': ['--disable-e10s'], 16:19:08 INFO - 'tests': ['tp5o']}, 16:19:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:08 INFO - 'tests': ['tp5o']}, 16:19:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:08 INFO - 'talos_options': ['--disable-e10s', 16:19:08 INFO - '--xperf_path', 16:19:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:08 INFO - 'tests': ['tp5n']}, 16:19:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:08 INFO - 'talos_options': ['--xperf_path', 16:19:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:08 INFO - 'tests': ['tp5n']}}, 16:19:08 INFO - 'talos.zip': {'path': '', 16:19:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:19:08 INFO - Running post-action listener: _resource_record_post_action 16:19:08 INFO - [mozharness: 2016-08-02 23:19:08.795936Z] Finished populate-webroot step (success) 16:19:08 INFO - [mozharness: 2016-08-02 23:19:08.796035Z] Running create-virtualenv step. 16:19:08 INFO - Running pre-action listener: _resource_record_pre_action 16:19:08 INFO - Running main action method: create_virtualenv 16:19:08 INFO - Creating virtualenv /builds/slave/test/build/venv 16:19:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:19:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:19:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:19:08 INFO - Using real prefix '/usr' 16:19:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:19:09 INFO - Installing distribute.............................................................................................................................................................................................done. 16:19:11 INFO - Installing pip.................done. 16:19:11 INFO - Return code: 0 16:19:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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')]} 16:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2784030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a45180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:19: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', 'pip>=1.5'] in /builds/slave/test/build 16:19: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 pip>=1.5 16:19:11 INFO - Using env: {'DISPLAY': ':0', 16:19:11 INFO - 'HOME': '/home/cltbld', 16:19:11 INFO - 'LANG': 'en_US.UTF-8', 16:19:11 INFO - 'LANGUAGE': 'en_US:en', 16:19:11 INFO - 'LOGNAME': 'cltbld', 16:19:11 INFO - 'MAIL': '/var/mail/cltbld', 16:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:11 INFO - 'MOZ_NO_REMOTE': '1', 16:19:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:11 INFO - 'NO_EM_RESTART': '1', 16:19:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:11 INFO - 'PWD': '/builds/slave/test', 16:19:11 INFO - 'SHELL': '/bin/bash', 16:19:11 INFO - 'SHLVL': '1', 16:19:11 INFO - 'TERM': 'linux', 16:19:11 INFO - 'TMOUT': '86400', 16:19:11 INFO - 'USER': 'cltbld', 16:19:11 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:11 INFO - '_': '/tools/buildbot/bin/python'} 16:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:19:11 INFO - Cleaning up... 16:19:11 INFO - Return code: 0 16:19:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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')]} 16:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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', '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 0x2784030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a45180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:19: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:19: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 psutil>=3.1.1 16:19:11 INFO - Using env: {'DISPLAY': ':0', 16:19:11 INFO - 'HOME': '/home/cltbld', 16:19:11 INFO - 'LANG': 'en_US.UTF-8', 16:19:11 INFO - 'LANGUAGE': 'en_US:en', 16:19:11 INFO - 'LOGNAME': 'cltbld', 16:19:11 INFO - 'MAIL': '/var/mail/cltbld', 16:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:11 INFO - 'MOZ_NO_REMOTE': '1', 16:19:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:11 INFO - 'NO_EM_RESTART': '1', 16:19:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:11 INFO - 'PWD': '/builds/slave/test', 16:19:11 INFO - 'SHELL': '/bin/bash', 16:19:11 INFO - 'SHLVL': '1', 16:19:11 INFO - 'TERM': 'linux', 16:19:11 INFO - 'TMOUT': '86400', 16:19:11 INFO - 'USER': 'cltbld', 16:19:11 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:11 INFO - '_': '/tools/buildbot/bin/python'} 16:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:11 INFO - Downloading/unpacking psutil>=3.1.1 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:19:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:13 INFO - Installing collected packages: psutil 16:19:13 INFO - Running setup.py install for psutil 16:19:13 INFO - building 'psutil._psutil_linux' extension 16:19:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:19:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:19:14 INFO - building 'psutil._psutil_posix' extension 16:19:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:19:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:19:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:14 INFO - Successfully installed psutil 16:19:14 INFO - Cleaning up... 16:19:14 INFO - Return code: 0 16:19:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:14 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 0x2784030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a45180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:19:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:19:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:19:14 INFO - Using env: {'DISPLAY': ':0', 16:19:14 INFO - 'HOME': '/home/cltbld', 16:19:14 INFO - 'LANG': 'en_US.UTF-8', 16:19:14 INFO - 'LANGUAGE': 'en_US:en', 16:19:14 INFO - 'LOGNAME': 'cltbld', 16:19:14 INFO - 'MAIL': '/var/mail/cltbld', 16:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:14 INFO - 'MOZ_NO_REMOTE': '1', 16:19:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:14 INFO - 'NO_EM_RESTART': '1', 16:19:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:14 INFO - 'PWD': '/builds/slave/test', 16:19:14 INFO - 'SHELL': '/bin/bash', 16:19:14 INFO - 'SHLVL': '1', 16:19:14 INFO - 'TERM': 'linux', 16:19:14 INFO - 'TMOUT': '86400', 16:19:14 INFO - 'USER': 'cltbld', 16:19:14 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:14 INFO - '_': '/tools/buildbot/bin/python'} 16:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:19:16 INFO - Installing collected packages: mozsystemmonitor 16:19:16 INFO - Running setup.py install for mozsystemmonitor 16:19:16 INFO - Successfully installed mozsystemmonitor 16:19:16 INFO - Cleaning up... 16:19:16 INFO - Return code: 0 16:19:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:16 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 0x2784030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a45180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:19:16 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 16:19:16 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 16:19:16 INFO - Using env: {'DISPLAY': ':0', 16:19:16 INFO - 'HOME': '/home/cltbld', 16:19:16 INFO - 'LANG': 'en_US.UTF-8', 16:19:16 INFO - 'LANGUAGE': 'en_US:en', 16:19:16 INFO - 'LOGNAME': 'cltbld', 16:19:16 INFO - 'MAIL': '/var/mail/cltbld', 16:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:16 INFO - 'MOZ_NO_REMOTE': '1', 16:19:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:16 INFO - 'NO_EM_RESTART': '1', 16:19:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:16 INFO - 'PWD': '/builds/slave/test', 16:19:16 INFO - 'SHELL': '/bin/bash', 16:19:16 INFO - 'SHLVL': '1', 16:19:16 INFO - 'TERM': 'linux', 16:19:16 INFO - 'TMOUT': '86400', 16:19:16 INFO - 'USER': 'cltbld', 16:19:16 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:16 INFO - '_': '/tools/buildbot/bin/python'} 16:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:16 INFO - Downloading/unpacking jsonschema==2.5.1 16:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:19:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:18 INFO - Downloading functools32-3.2.3-2.tar.gz 16:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:19:18 INFO - warning: no files found matching '*.txt' 16:19:18 INFO - no previously-included directories found matching 'build' 16:19:18 INFO - no previously-included directories found matching 'dist' 16:19:18 INFO - no previously-included directories found matching '.git*' 16:19:19 INFO - Installing collected packages: jsonschema, functools32 16:19:19 INFO - Running setup.py install for functools32 16:19:19 INFO - warning: no files found matching '*.txt' 16:19:19 INFO - no previously-included directories found matching 'build' 16:19:19 INFO - no previously-included directories found matching 'dist' 16:19:19 INFO - no previously-included directories found matching '.git*' 16:19:19 INFO - Successfully installed jsonschema functools32 16:19:19 INFO - Cleaning up... 16:19:19 INFO - Return code: 0 16:19:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:19 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 0x2784030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a45180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:19:19 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 16:19:19 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 16:19:19 INFO - Using env: {'DISPLAY': ':0', 16:19:19 INFO - 'HOME': '/home/cltbld', 16:19:19 INFO - 'LANG': 'en_US.UTF-8', 16:19:19 INFO - 'LANGUAGE': 'en_US:en', 16:19:19 INFO - 'LOGNAME': 'cltbld', 16:19:19 INFO - 'MAIL': '/var/mail/cltbld', 16:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:19 INFO - 'MOZ_NO_REMOTE': '1', 16:19:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:19 INFO - 'NO_EM_RESTART': '1', 16:19:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:19 INFO - 'PWD': '/builds/slave/test', 16:19:19 INFO - 'SHELL': '/bin/bash', 16:19:19 INFO - 'SHLVL': '1', 16:19:19 INFO - 'TERM': 'linux', 16:19:19 INFO - 'TMOUT': '86400', 16:19:19 INFO - 'USER': 'cltbld', 16:19:19 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:19 INFO - '_': '/tools/buildbot/bin/python'} 16:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:19:19 INFO - Cleaning up... 16:19:19 INFO - Return code: 0 16:19:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:19 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 0x2784030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a45180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:19:19 INFO - Using env: {'DISPLAY': ':0', 16:19:19 INFO - 'HOME': '/home/cltbld', 16:19:19 INFO - 'LANG': 'en_US.UTF-8', 16:19:19 INFO - 'LANGUAGE': 'en_US:en', 16:19:19 INFO - 'LOGNAME': 'cltbld', 16:19:19 INFO - 'MAIL': '/var/mail/cltbld', 16:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:19 INFO - 'MOZ_NO_REMOTE': '1', 16:19:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:19 INFO - 'NO_EM_RESTART': '1', 16:19:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:19 INFO - 'PWD': '/builds/slave/test', 16:19:19 INFO - 'SHELL': '/bin/bash', 16:19:19 INFO - 'SHLVL': '1', 16:19:19 INFO - 'TERM': 'linux', 16:19:19 INFO - 'TMOUT': '86400', 16:19:19 INFO - 'USER': 'cltbld', 16:19:19 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:19 INFO - '_': '/tools/buildbot/bin/python'} 16:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:19 INFO - Downloading/unpacking blobuploader==1.2.4 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:21 INFO - Downloading blobuploader-1.2.4.tar.gz 16:19:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:19:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:19:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:22 INFO - Downloading docopt-0.6.1.tar.gz 16:19:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:19:22 INFO - Installing collected packages: blobuploader, requests, docopt 16:19:22 INFO - Running setup.py install for blobuploader 16:19:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:19:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:19:22 INFO - Running setup.py install for requests 16:19:22 INFO - Running setup.py install for docopt 16:19:22 INFO - Successfully installed blobuploader requests docopt 16:19:22 INFO - Cleaning up... 16:19:22 INFO - Return code: 0 16:19:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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')]} 16:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:22 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 0x2784030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a45180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:19:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:19:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:19:22 INFO - Using env: {'DISPLAY': ':0', 16:19:22 INFO - 'HOME': '/home/cltbld', 16:19:22 INFO - 'LANG': 'en_US.UTF-8', 16:19:22 INFO - 'LANGUAGE': 'en_US:en', 16:19:22 INFO - 'LOGNAME': 'cltbld', 16:19:22 INFO - 'MAIL': '/var/mail/cltbld', 16:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:22 INFO - 'MOZ_NO_REMOTE': '1', 16:19:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:22 INFO - 'NO_EM_RESTART': '1', 16:19:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:22 INFO - 'PWD': '/builds/slave/test', 16:19:22 INFO - 'SHELL': '/bin/bash', 16:19:22 INFO - 'SHLVL': '1', 16:19:22 INFO - 'TERM': 'linux', 16:19:22 INFO - 'TMOUT': '86400', 16:19:22 INFO - 'USER': 'cltbld', 16:19:22 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:22 INFO - '_': '/tools/buildbot/bin/python'} 16:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:23 INFO - Running setup.py (path:/tmp/pip-h2yXdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:23 INFO - Running setup.py (path:/tmp/pip-361UuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:23 INFO - Running setup.py (path:/tmp/pip-hmQrq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:23 INFO - Running setup.py (path:/tmp/pip-RB7qhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:23 INFO - Running setup.py (path:/tmp/pip-NpyVEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:23 INFO - Running setup.py (path:/tmp/pip-MYxZP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:23 INFO - Running setup.py (path:/tmp/pip-5DJqOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:23 INFO - Running setup.py (path:/tmp/pip-zHdBZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:23 INFO - Running setup.py (path:/tmp/pip-M36VlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:23 INFO - Running setup.py (path:/tmp/pip-kbGyqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:23 INFO - Running setup.py (path:/tmp/pip-EOdW9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:23 INFO - Running setup.py (path:/tmp/pip-JiNm45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:23 INFO - Running setup.py (path:/tmp/pip-SV1cpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:24 INFO - Running setup.py (path:/tmp/pip-MToq_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:24 INFO - Running setup.py (path:/tmp/pip-nCPC2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:24 INFO - Running setup.py (path:/tmp/pip-NrE4SW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:24 INFO - Running setup.py (path:/tmp/pip-pXO2gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:19:24 INFO - Running setup.py install for manifestparser 16:19:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:19:24 INFO - Running setup.py install for mozcrash 16:19:24 INFO - Running setup.py install for mozdebug 16:19:24 INFO - Running setup.py install for mozdevice 16:19:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:19:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:19:24 INFO - Running setup.py install for mozfile 16:19:24 INFO - Running setup.py install for mozhttpd 16:19:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:19:24 INFO - Running setup.py install for mozinfo 16:19:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozInstall 16:19:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozleak 16:19:25 INFO - Running setup.py install for mozlog 16:19:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for moznetwork 16:19:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozprocess 16:19:25 INFO - Running setup.py install for mozprofile 16:19:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozrunner 16:19:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozscreenshot 16:19:25 INFO - Running setup.py install for moztest 16:19:26 INFO - Running setup.py install for mozversion 16:19:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:19:26 INFO - Cleaning up... 16:19:26 INFO - Return code: 0 16:19:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:26 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 0x2784030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a45180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:19:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:19:26 INFO - Using env: {'DISPLAY': ':0', 16:19:26 INFO - 'HOME': '/home/cltbld', 16:19:26 INFO - 'LANG': 'en_US.UTF-8', 16:19:26 INFO - 'LANGUAGE': 'en_US:en', 16:19:26 INFO - 'LOGNAME': 'cltbld', 16:19:26 INFO - 'MAIL': '/var/mail/cltbld', 16:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:26 INFO - 'MOZ_NO_REMOTE': '1', 16:19:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:26 INFO - 'NO_EM_RESTART': '1', 16:19:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:26 INFO - 'PWD': '/builds/slave/test', 16:19:26 INFO - 'SHELL': '/bin/bash', 16:19:26 INFO - 'SHLVL': '1', 16:19:26 INFO - 'TERM': 'linux', 16:19:26 INFO - 'TMOUT': '86400', 16:19:26 INFO - 'USER': 'cltbld', 16:19:26 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:26 INFO - '_': '/tools/buildbot/bin/python'} 16:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:26 INFO - Running setup.py (path:/tmp/pip-U0W8q9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:26 INFO - Running setup.py (path:/tmp/pip-B1i8Zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:26 INFO - Running setup.py (path:/tmp/pip-6yd3_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:26 INFO - Running setup.py (path:/tmp/pip-4n8b3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:26 INFO - Running setup.py (path:/tmp/pip-e2ylRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:26 INFO - Running setup.py (path:/tmp/pip-7eCQ9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:26 INFO - Running setup.py (path:/tmp/pip-uJCuOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:26 INFO - Running setup.py (path:/tmp/pip-w6caxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:26 INFO - Running setup.py (path:/tmp/pip-vsZ8om-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:27 INFO - Running setup.py (path:/tmp/pip-s1i5dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:27 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)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:27 INFO - Running setup.py (path:/tmp/pip-2sCpq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:27 INFO - Running setup.py (path:/tmp/pip-ezKqSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:27 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)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:27 INFO - Running setup.py (path:/tmp/pip-7_roAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:27 INFO - Running setup.py (path:/tmp/pip-H11zBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:27 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)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:27 INFO - Running setup.py (path:/tmp/pip-I5cklG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:27 INFO - Running setup.py (path:/tmp/pip-UKaXw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:27 INFO - Running setup.py (path:/tmp/pip-1HkGyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:19:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:29 INFO - Downloading blessings-1.6.tar.gz 16:19:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:19:29 INFO - Installing collected packages: blessings 16:19:29 INFO - Running setup.py install for blessings 16:19:29 INFO - Successfully installed blessings 16:19:29 INFO - Cleaning up... 16:19:29 INFO - Return code: 0 16:19:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:19:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:29 INFO - Reading from file tmpfile_stdout 16:19:29 INFO - Current package versions: 16:19:29 INFO - argparse == 1.2.1 16:19:29 INFO - blessings == 1.6 16:19:29 INFO - blobuploader == 1.2.4 16:19:29 INFO - docopt == 0.6.1 16:19:29 INFO - functools32 == 3.2.3-2 16:19:29 INFO - jsonschema == 2.5.1 16:19:29 INFO - manifestparser == 1.1 16:19:29 INFO - mozInstall == 1.12 16:19:29 INFO - mozcrash == 0.17 16:19:29 INFO - mozdebug == 0.1 16:19:29 INFO - mozdevice == 0.48 16:19:29 INFO - mozfile == 1.2 16:19:29 INFO - mozhttpd == 0.7 16:19:29 INFO - mozinfo == 0.9 16:19:29 INFO - mozleak == 0.1 16:19:29 INFO - mozlog == 3.3 16:19:29 INFO - moznetwork == 0.27 16:19:29 INFO - mozprocess == 0.23 16:19:29 INFO - mozprofile == 0.28 16:19:29 INFO - mozrunner == 6.12 16:19:29 INFO - mozscreenshot == 0.1 16:19:29 INFO - mozsystemmonitor == 0.3 16:19:29 INFO - moztest == 0.7 16:19:29 INFO - mozversion == 1.4 16:19:29 INFO - psutil == 3.1.1 16:19:29 INFO - requests == 1.2.3 16:19:29 INFO - wsgiref == 0.1.2 16:19:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:29 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 0x2784030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a45180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:19:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:19:29 INFO - Using env: {'DISPLAY': ':0', 16:19:29 INFO - 'HOME': '/home/cltbld', 16:19:29 INFO - 'LANG': 'en_US.UTF-8', 16:19:29 INFO - 'LANGUAGE': 'en_US:en', 16:19:29 INFO - 'LOGNAME': 'cltbld', 16:19:29 INFO - 'MAIL': '/var/mail/cltbld', 16:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:29 INFO - 'MOZ_NO_REMOTE': '1', 16:19:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:29 INFO - 'NO_EM_RESTART': '1', 16:19:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:29 INFO - 'PWD': '/builds/slave/test', 16:19:29 INFO - 'SHELL': '/bin/bash', 16:19:29 INFO - 'SHLVL': '1', 16:19:29 INFO - 'TERM': 'linux', 16:19:29 INFO - 'TMOUT': '86400', 16:19:29 INFO - 'USER': 'cltbld', 16:19:29 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:29 INFO - '_': '/tools/buildbot/bin/python'} 16:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:19:30 INFO - Cleaning up... 16:19:30 INFO - Return code: 0 16:19:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2784030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a45180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:19:30 INFO - Using env: {'DISPLAY': ':0', 16:19:30 INFO - 'HOME': '/home/cltbld', 16:19:30 INFO - 'LANG': 'en_US.UTF-8', 16:19:30 INFO - 'LANGUAGE': 'en_US:en', 16:19:30 INFO - 'LOGNAME': 'cltbld', 16:19:30 INFO - 'MAIL': '/var/mail/cltbld', 16:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:30 INFO - 'MOZ_NO_REMOTE': '1', 16:19:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:30 INFO - 'NO_EM_RESTART': '1', 16:19:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:30 INFO - 'PWD': '/builds/slave/test', 16:19:30 INFO - 'SHELL': '/bin/bash', 16:19:30 INFO - 'SHLVL': '1', 16:19:30 INFO - 'TERM': 'linux', 16:19:30 INFO - 'TMOUT': '86400', 16:19:30 INFO - 'USER': 'cltbld', 16:19:30 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:30 INFO - '_': '/tools/buildbot/bin/python'} 16:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:19:30 INFO - Cleaning up... 16:19:30 INFO - Return code: 0 16:19:30 INFO - Running post-action listener: _resource_record_post_action 16:19:30 INFO - Running post-action listener: _start_resource_monitoring 16:19:30 INFO - Starting resource monitoring. 16:19:30 INFO - [mozharness: 2016-08-02 23:19:30.422148Z] Finished create-virtualenv step (success) 16:19:30 INFO - [mozharness: 2016-08-02 23:19:30.422653Z] Running install step. 16:19:30 INFO - Running pre-action listener: _resource_record_pre_action 16:19:30 INFO - Running main action method: install 16:19:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:30 INFO - Reading from file tmpfile_stdout 16:19:30 INFO - Detecting whether we're running mozinstall >=1.0... 16:19:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:19:30 INFO - Reading from file tmpfile_stdout 16:19:30 INFO - Output received: 16:19:30 INFO - Usage: mozinstall [options] installer 16:19:30 INFO - Options: 16:19:30 INFO - -h, --help show this help message and exit 16:19:30 INFO - -d DEST, --destination=DEST 16:19:30 INFO - Directory to install application into. [default: 16:19:30 INFO - "/builds/slave/test"] 16:19:30 INFO - --app=APP Application being installed. [default: firefox] 16:19:30 INFO - mkdir: /builds/slave/test/build/application 16:19:30 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'] 16:19:30 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 16:19:47 INFO - Reading from file tmpfile_stdout 16:19:47 INFO - Output received: 16:19:47 INFO - /builds/slave/test/build/application/firefox/firefox 16:19:47 INFO - Running post-action listener: _resource_record_post_action 16:19:47 INFO - [mozharness: 2016-08-02 23:19:47.645619Z] Finished install step (success) 16:19:47 INFO - [mozharness: 2016-08-02 23:19:47.645892Z] Running run-tests step. 16:19:47 INFO - Running pre-action listener: _resource_record_pre_action 16:19:47 INFO - Running main action method: run_tests 16:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:19:47 INFO - Python 2.7.3 16:19:47 INFO - Return code: 0 16:19:47 INFO - grabbing minidump binary from tooltool 16:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:47 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 0x2a433b0>, '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 0x2a442b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a44740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:19:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:19:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:19:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:19:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:19:47 INFO - Return code: 0 16:19:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:19:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:19:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:19:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:19:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:19:47 INFO - Using env: {'DISPLAY': ':0', 16:19:47 INFO - 'HOME': '/home/cltbld', 16:19:47 INFO - 'LANG': 'en_US.UTF-8', 16:19:47 INFO - 'LANGUAGE': 'en_US:en', 16:19:47 INFO - 'LOGNAME': 'cltbld', 16:19:47 INFO - 'MAIL': '/var/mail/cltbld', 16:19:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:19:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:47 INFO - 'MOZ_NO_REMOTE': '1', 16:19:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:19:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:47 INFO - 'NO_EM_RESTART': '1', 16:19:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:47 INFO - 'PWD': '/builds/slave/test', 16:19:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:19:47 INFO - 'SHELL': '/bin/bash', 16:19:47 INFO - 'SHLVL': '1', 16:19:47 INFO - 'TERM': 'linux', 16:19:47 INFO - 'TMOUT': '86400', 16:19:47 INFO - 'USER': 'cltbld', 16:19:47 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861', 16:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:47 INFO - '_': '/tools/buildbot/bin/python'} 16:19:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:19:47 INFO - mozversion application_buildid: 20160802133030 16:19:47 INFO - mozversion application_changeset: 73a57814a495b29244ef5377e73488a3f3fabb15 16:19:47 INFO - mozversion application_display_name: Nightly 16:19:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:19:47 INFO - mozversion application_name: Firefox 16:19:47 INFO - mozversion application_remotingname: firefox 16:19:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:19:47 INFO - mozversion application_vendor: Mozilla 16:19:47 INFO - mozversion application_version: 51.0a1 16:19:47 INFO - mozversion platform_buildid: 20160802133030 16:19:47 INFO - mozversion platform_changeset: 73a57814a495b29244ef5377e73488a3f3fabb15 16:19:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:19:47 INFO - mozversion platform_version: 51.0a1 16:19:47 INFO - using testdate: 1470179987 16:19:47 INFO - actual date: 1470179987 16:19:47 INFO - starting webserver on 'localhost:58327' 16:19:47 INFO - SUITE-START | Running 2 tests 16:19:47 INFO - TEST-START | dromaeo_css 16:19:47 INFO - operating with platform_type : linux_ 16:19:47 INFO - Initialising browser for dromaeo_css test... 16:19:47 INFO - TEST-INFO | started process 8209 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwH1acp/profile http://localhost:58327/getInfo.html) 16:19:51 INFO - PROCESS | 8209 | __metrics Screen width/height:1600/1200 16:19:51 INFO - PROCESS | 8209 | colorDepth:24 16:19:51 INFO - PROCESS | 8209 | Browser inner width/height: 1024/697 16:19:51 INFO - PROCESS | 8209 | __metrics 16:19:52 INFO - PROCESS | 8209 | 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" 16:19:52 INFO - PROCESS | 8209 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:19:53 INFO - PROCESS | 8209 | console.error: PushService: 16:19:53 INFO - PROCESS | 8209 | stateChangeProcessEnqueue: Error transitioning state 16:19:53 INFO - PROCESS | 8209 | UnknownError 16:19:53 INFO - TEST-INFO | 8209: exit 0 16:19:53 INFO - Browser initialized. 16:19:53 INFO - Running cycle 1/1 for dromaeo_css test... 16:19:53 INFO - TEST-INFO | started process 8290 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwH1acp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:05 INFO - PROCESS | 8290 | RSS: Main: 156045312 16:20:05 INFO - PROCESS | 8290 | 16:20:57 INFO - PROCESS | 8290 | 1470180057204 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:23:06 INFO - PROCESS | 8290 | Cycle 1(1): loaded http://localhost:58327/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58327/tests/dromaeo/cssquery-ext.html) 16:23:07 INFO - PROCESS | 8290 | RSS: Main: 273850368 16:23:07 INFO - PROCESS | 8290 | 16:26:09 INFO - PROCESS | 8290 | Cycle 1(1): loaded http://localhost:58327/tests/dromaeo/cssquery-ext.html (next: http://localhost:58327/tests/dromaeo/cssquery-jquery.html) 16:26:10 INFO - PROCESS | 8290 | RSS: Main: 247144448 16:26:10 INFO - PROCESS | 8290 | 16:29:12 INFO - PROCESS | 8290 | Cycle 1(1): loaded http://localhost:58327/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58327/tests/dromaeo/cssquery-mootools.html) 16:29:13 INFO - PROCESS | 8290 | RSS: Main: 187744256 16:29:13 INFO - PROCESS | 8290 | 16:32:15 INFO - PROCESS | 8290 | Cycle 1(1): loaded http://localhost:58327/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58327/tests/dromaeo/cssquery-prototype.html) 16:32:15 INFO - PROCESS | 8290 | RSS: Main: 186232832 16:32:15 INFO - PROCESS | 8290 | 16:35:18 INFO - PROCESS | 8290 | Cycle 1(1): loaded http://localhost:58327/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58327/tests/dromaeo/cssquery-yui.html) 16:35:18 INFO - PROCESS | 8290 | RSS: Main: 185741312 16:35:18 INFO - PROCESS | 8290 | 16:38:21 INFO - PROCESS | 8290 | Cycle 1(1): loaded http://localhost:58327/tests/dromaeo/cssquery-yui.html (next: http://localhost:58327/tests/dromaeo/cssquery-dojo.html) 16:38:21 INFO - PROCESS | 8290 | RSS: Main: 275083264 16:38:21 INFO - PROCESS | 8290 | 16:38:21 INFO - PROCESS | 8290 | __start_tp_report 16:38:21 INFO - PROCESS | 8290 | _x_x_mozilla_page_load 16:38:21 INFO - PROCESS | 8290 | _x_x_mozilla_page_load_details 16:38:21 INFO - PROCESS | 8290 | |i|pagename|runs| 16:38:21 INFO - PROCESS | 8290 | |0;dojo.html;3050.557669137974;3050.6796786337436;3077.907662770116;3092.9381412371768;3101.798383105098;5196.090684130281;5218.843434696955;5344.812931547392;5398.48714372135;5438.456154384559;5365.168398898168;5389.51494365507;5407.134858422653;5426.240326354314;5444.074507333753;4827.155247831633;4911.091448082117;4917.680350777206;4932.556069953704;5058.064258112261;4949.604031677475;4971.080350135237;4997.825076122336;5007.524285192901;5010.799568017275;4734.337192793029;4762.857114286577;4849.224124140156;4885.2427873679635;4962.875928101808;4832.830850920201;4844.66087373884;4851.078295123951;4852.417709874802;4943.851684449436;4828.155072862271;4847.75761211938;4855.732934688591;4874.000829829876;4915.5330243626795;5160.664556803796;5308.124159513675;5338.252644629755;5362.544183744387;5384.973075134639;5759.136129580555;5796.391378905197;5801.622894511843;5806.012977793743;5810.82567522975;5788.102844059177;5794.797182098649;5828;5835.4748072673665;5845.035569131088;5133.358330208724;5327.653702509325;5370.3287089113865;5379.300690910157;5444.455554444603;5924.496417804535;5979.760809567656;5990;5993.760249589967;5993.850153746192;5681.431856814368;5683.488486036276;5702.429757024264;5710.371859095496;5734.913976290359;5431.646942948775;5649.12438572022;5692.117721753135;5696.544276457874;5795.623284486579;5712;5716.114002329646;5721.141828725725;5721.485066344049;5724.513416359656;5670.829875103754;5696.857578560569;5731.684757338387;5733.254676892061;5765.164051212634;4289.592488713567;4339.240632889232;4403.3174857897075;4413.668974826901;4458.621017213508;3935.8819235422984;3936.0553467167674;3936.0553467168247;3973;3973.4834471518516;5426.240326354314;5435.809746658927;5437.184422336682;5454.563634909197;5503.669779813144;5514.751836167382;5707.942920570741;5747.798827041033;5757.913631295534;5844.240248767718;5313.44208858072;5363.624546281801;5363.785448582013;5368.1679339702405;5447.373552041487;4964.15609346412;4978.178600530872;4978.850634480971;4983.476734942851;5019.874503137451;5752.338481074647;5884.470397664145;5973.342932277446;5976.850578735566;5998.3401825798255;5692.231548740867;5705.486506214461;5706.144078388275;5706.80026199081;5758.8848223034465;5996.280446346466;5999.430054144849;6003.639781612943;6008.309044459768;6009.849753756191;5266.236395722675;5584.078627026495;5594.0769772987005;5636.323641163087;5726.0838265877255;5743.9138412923;5767.3367562729145;5771.394003629726;5775.595708300547;5778.479936805707;5632.493075623213;5643.040683083967;5645.774169033192;5648.096304591246;5654.236678048575;5434.2935418395355;5548.250986116984;5587.664740115606;5608.8878222434505;5704.172894930295;5534.7786088556;5574;5587.497125258747;5589.07780216276;5590.077637189818;5644.8024319148635;5667.603267771216;5696.401877802771;5717.22817419643;5720.656760594377;5276.841694749163;5311.893762124813;5427.782888684408;5439.728013599383;5481.808136715196 16:38:21 INFO - PROCESS | 8290 | |1;ext.html;571.3714913595046;586.7476984896495;610.8320211941718;619.2197830733276;632.2602555010637;33392.66607333929;34076.65923340769;36261.0934726632;46074.30888536674;47255.29117063238;47109.52890471094;47754.76122619391;47766.28350574736;47784.28323575149;47801;9109.271258299337;9267.304952128618;9268.72193834186;9269.629214831433;9274.165325120737;9056.501892395916;9143.03998080202;9156.084391560857;9161.129692679218;9161.496117713523;9202.76993075175;9238.769030774218;9251.58367873444;9254.167124958753;9256.25949924008;9135.95432022839;9159.221466175382;9171.266298696088;9173.403728757676;9186.173244408003;9103.63585456581;9172.266218702487;9175.770605734844;9178;9185.540722963826;43574.782126089485;44284.335734964;44337.556624433666;44341.33487997683;44356.556434435566;3542.5217595624517;3636.2909232699635;3649.835757390924;3661.8901432957055;3984.561698213194;33760.49359259613;33798.6620133798;34032;34178.658213417795;35805.28389432197;4295.355696645497;4356.455443069616;4398.582134697199;4497.308646499275;4502.302143167782;371815;372825;375275;375754;376327;62090.68954655198;62508.687456562424;62524;62541;62663.373366266664;64837.67581162064;64920.67539662366;65133.67433162899;65232;65284.673576631816;14003.649908752363;14215.50245741394;14240.928795355956;14243.501477448446;14250.714985700228;10575.947120264349;10602.628907988183;10681.626143084955;10687.30532515384;10690.732731681615;15677.686446271011;17801.554961126076;17854.285828566713;17872.642547148986;17911.73132403015;5830.416958304136;5925.940740592625;5954.732037058343;6016.819495415145;6074.757009719562;979.6522234606755;1113.3987646670869;1121.327203677803;1127.025123268367;1147.0307590086363;34277;34332.65667343345;34725.82637086798;34833.10302165597;35189.12027199289;1200.9256303862142;1209.3469526455792;1220.5117952818766;1222.706550427891;1274.8725127487362;17613.7357939631;17768.644627107384;17933;17963.371282005068;17964.461066168034;570157;576146;576839;576923;577308;62314.0652890207;62518.37481625126;62655.37344626495;62686;62722;1194.75507520958;1331.1414137881013;1341.3494455189225;1356.6133651909158;1383.6471699716305;33638.663613363555;35564.64435355717;35632.10919727028;35778.463323049655;36027;54046.729766350916;54622.453775461734;54667.179992300946;54692;54744.72627636836;422158;424886;425511;426329;427553;36442.8177859109;43186.78406607947;43679.563204368824;44512.33231501595;44668.77665611651;50058.74970625269;50160.74919625378;50889.745551272004;51254.74372628113;51644.74177629088;42529;43009.13981720286;43981;44151.558484414745;45367;43231.567684322756;43328.56671433245;44226;44686;45132.77433612811;43685.78157109321;44634;44716.10567788691;44721.32918006167;44962.77518612386;37173.256534869695;38532.614673852906;39470.60529394669;40067.59932400638;40326.1934761309;29722.40555188841;29746.85126574353;29900.252493687833;30174.698253017188;30264 16:38:21 INFO - PROCESS | 8290 | |2;jquery.html;392.4192195550583;393.5454549994755;394.9684025277978;403.1493548612427;424.885280974137;13492.730145397087;18716.157772900217;19647.214111435525;20291.39125826224;20372.89813550934;14654.926725366386;18854;19597.902010489965;19669.803301966975;19702.310419135334;5665.206871038058;5833.766649334021;5835.620684655484;5843.094320380326;5860.882782344351;5715.71421428929;5800.564957628195;5802.883942321151;5803.245578074844;5804.448577385142;5545.334559852823;5630.268065151545;5663.971680141593;5687.203791469197;5703.657780533161;5563.749631266603;5627.6904770237625;5639.492445679888;5645.520130788888;5645.774169033254;4863.902721945577;4989.401271847401;4994.051130285234;4995.675281106753;4997.075541024922;22798.88600557003;22868.085276588918;23446.296611101694;23596.646050309086;23695.763042369526;2387.7970372518357;2510.6234064890414;2510.8734687362776;2675.3712877473777;2903.3177203357195;14692.26538673062;14834.851651483563;14980.625484362869;15068.698626027528;15247.771283430646;1955.1529329782704;1961.4507937777446;2002.549426379053;2006.4181387397637;2044.9386518404472;80715.99999999942;80745.19254807495;82264.58867705683;82652.58673706593;83336.58331708363;10670.946645266724;10904.454777261264;11141.220114592063;11194.888051119548;11246.268992515625;15471.5358539244;15488.14815185153;15496.457623983106;15523.301451434487;15525.068495890282;7343.192248852622;7633.6564854580565;7712.498687585401;7893.605319733991;7900.288973992253;7209.6034718091005;7235.421166306683;7419.814504637319;7431.219721929119;7432.554046757212;5845.532357411397;6340.778072767524;6635.8009259722285;6796.8300792479595;6879.58722476653;1914.9904250478658;1977.4364306172672;1980.3266889257723;1994.3518356534184;2004.0981558298824;926.4533924984291;1077.9514921828536;1117.374220509157;1139.0602752729033;1152.9192956492966;20059.49851253701;20656;20669.483262918548;20733.792662073487;20984.160633574487;1007.7430255284855;1033.9741506462399;1055.8257887448638;1057.1912486947492;1079.1241341824107;10866;11623.360715160747;11641.941790290994;11667.416629168507;11732.824007639892;487701;490005;496022;501083;506842;75977;76283.61858190673;78525.60737196277;79395;81518.18481815225;992.8163289791412;994.3586386780486;1024.4314405504867;1026.471367245869;1028.922830787694;16988.66022679515;17899.668358172883;18098.638027239645;18197;18369;40078.198436030536;40411.595884040784;40516.797416012734;40590.18819623532;41984.80911453104;54090.45909540854;54419;54834.72582637221;55675.72162139163;55957;8346.290565301775;8666.176713212235;8720.738377848675;8919.10808919103;8962.596683149404;12193.939030304791;13113.40989655487;13263.933680331536;13509.662258443617;13786.931065344608;11320;11390.088792896715;12065.336406497727;12139.332336721565;12413.193142445713;11141.665750027512;11394;11895.345755983504;11901.940490297493;11903.40482975865;11504.482298296429;11679.532818687498;11773.76452470963;11943.820842687193;12117.75764484723;8540.52759707867;8616.439931404439;8629.482231066157;8856.291496680122;8942.68700595476;10881.945590271998;11207.71980700489;11291.153163512934;11390.202685811913;11600.245984011011 16:38:21 INFO - PROCESS | 8290 | |3;mootools.html;1338.0232430325868;1475.3508456279233;1500.6398464368551;1646.2509558151035;1794.4796009157344;7616.466847320689;8773.122687731224;9060.139286767762;9064.546772661373;9330.346875718702;9129.269658427327;9154.084591540843;9203.907960920405;9208.815823683539;9223.49270790108;2376.298991797415;2401.987990060047;2401.987990060056;2403.0267741564694;2407.759224077587;2398.82008849337;2420.286015625386;2448.8897999590063;2454.828162028659;2459.9262022139364;2411.6985376827897;2411.8432301900407;2423.781859632633;2441.218809980807;2441.401856545149;2329.6971393718877;2352.188494969233;2353.105819788478;2358.3043002314357;2360.409897525619;2329.5690297294886;2344.9531009379716;2353.2587235021083;2353.611654077092;2359.2686267257204;9208.907910920872;9227.630894764201;9280.72157835266;9284.907150928471;9311.255099592016;5364.409914909357;5571.415001424833;5578.804741834057;5591.944080559183;5604.887902241932;3361.445361515347;3378.9493157602656;3397.065806903097;3428.639992800745;3434.587849458056;1222.2422098299112;1229.0966139887175;1236.2767780848214;1237.900967922564;2112.5317904056687;12793;12983.026273029465;12985.285809280485;12986.935065324707;12992.090553661248;4679.578837904605;4741.506425476033;4748.762561871893;4786.664933454695;5044.798208071636;10173.54219060144;10220.744481388025;10263.12763415118;10263.281570290008;10266.486675666336;5480.588955828329;5510.6969116698965;5512.035393806068;5512.91730624041;5516.420775818561;4109.506859176858;4327.156204540147;4483.820647174077;4906.215005599159;5144.382674079135;3927.4108883667677;3950.5654378018394;4040.1717647882115;4066.1664358806374;4067.7762723049923;1183.9408029598485;1210.497643477947;1216.7992281273491;1217.452146534063;1224.1186345830988;877.5787621941504;926.6617684545092;1043.1341986151476;1043.4313299251926;1061.1033676543464;6115.84710382244;6183.227096612924;6487.805365839033;6544.509161812883;6569.737210511622;449.0905915521096;462;464.07880357490353;465.9510751371057;1036.445501656625;4276.743395396286;4305.720128191658;4339;4379.299312110047;4448.621867141264;11239.100871930226;11630.70923226926;11671;11673.824892626617;11695.181337306478;11499.425028748696;11554.76890462186;11558.364289963965;11559.884401156049;11562.421878906021;8194.713185038496;8437.156284371637;8513.914860851311;8638.611262493203;8687.34844886636;5959.821205363846;6025.1564780931585;6217.285012223652;6283.528735344776;6429.453496452854;10658.786824263627;10688.679339619825;10855.185861060294;10903.618373356894;10906.236563440483;10608.734781630521;10620.52207650642;10915.56337746513;10961.013508784248;11003.669889903316;8224.62989165477;8260.752177434686;8275.089740128702;8374.07885132647;8393;8967.686130985385;9015.639374425213;9030.954845225995;9044.547772611211;9047.592858321259;7900.367970562456;7996.080450748006;8060.717874874351;8119.837603247784;8307;7427.294407031323;7432.702691892264;7577.5453472791805;7674.577898215429;7928.286454219148;7370.6683199255085;7488.737894173678;7524.5861477619255;7527.3601743850195;7530.096388433423;5282.867928301823;5423;5425.891482170256;5446.836594902159;5551.722413879209;5636.2954630671165;5710.086386178192;5726.284214473191;5799.362070172362;5827.213326200908 16:38:21 INFO - PROCESS | 8290 | |4;prototype.html;321.4503199528806;326.96566860479646;330.4976435817557;337.16887871397;338.54972886061523;5195.58435325174;5216.66091704039;5220.608454365928;5227;5230.267762513251;5157.343967856556;5160.019596276705;5190.299309593203;5258.658187217838;5261.210818377245;3892.5912779158207;3936.5866584008695;4056.71602987791;4081.1429599783933;4107.034846810997;4087.734297270668;4091.89770255743;4096.221717873609;4107.1580326033245;4108.3221268490815;4054.756714597119;4062.4718786557705;4086.8569600064;4097.569755175693;4118.732282401649;3885.6308650678284;3909.2767837949928;4044.676425885922;4049.250888585592;4061.715679902408;3554.1647712787476;3558.62634423387;3562.8931132066077;3568.3398571264397;3569.37535931211;5087.643864929457;5087.771050302746;5120.15517439622;5121.897562048775;5140.7172605506685;4380.408644832939;4521.547845215452;4546.272596384596;4549.522300158502;4581.221192397301;5347.331583552056;5348.090824559834;5361.544268737162;5363.7127089498235;5385.946140538584;3849.6727778138893;3862.6523612874976;3871.6515513603913;3886.689064874803;3892.3772196448717;5478.150886612541;5514;5535.335759708821;5545.894299192465;5632.5073855428345;3926.9410958835642;3933.547642021147;3936.7244292899795;3959.089409435788;3976.0855003348815;4006.1587066715733;4019.698522610816;4021.5978402160135;4025.2754504188943;4033.3344998075577;4466.977665111654;4587.197240482902;4587.724736515753;4598.448186217608;4604.447466304065;5351.3578370595;5355.30381050461;5357.919631205535;5384.596155288369;5428.4028756836715;1369.4935570871962;1390.5897760160776;1391.2973948156116;1402.8106205662104;1404.2767974493145;908.1780988205685;910.1444642036464;922.3036607361399;933.169479163545;962.1965658674944;5120.820771272987;5135.35808023997;5156.8968620627375;5159.535641792257;5194.532492075731;5415.214793854869;5425.674459532441;5434.864128396822;5439.782408703687;5454.236406903036;5022.924656130161;5143.665661731976;5145.434002259748;5164.535191832753;5178.71517066565;2889.4076714273524;2900.7244311790346;2912.8834846606323;2914.7668186545025;2916.6354205724283;5651.576131790132;5662.801801936995;5681.517071049007;5685.204071430003;5705.62913410627;5325.334333208349;5482.506574408322;5491.560675145979;5551.472610102034;5551.694656793915;5631.29608798892;5637.3235211774845;5640.830775076754;5644.729935764502;5650.886982260249;5273.920891186712;5414.133738601805;5426.620136590401;5428.945710542844;5450.8909821802545;5388.299521062237;5409.2427060212385;5412.535976974661;5426.511613954763;5434.619576629598;5593.860153496195;5616.66300022;5632.521690456186;5638.802641907513;5651.88696226065;3878.2825177342283;3964.484616999887;3973.582773808824;4015.1768887377434;4055.9720139930037;5449.6730196188355;5453.427390123979;5466.34403871538;5472.589555783253;5479.479449452296;5333.626646134733;5356.250124982584;5420.13277875538;5432.266644003009;5432.483914028161;5205.286993259227;5303.867403314794;5382.461753824587;5407.891842163056;5434.2935418395355;5275.577953763766;5290.153575427913;5331.747633943214;5333;5339.3058902342445;5207.4271830097905;5360;5409.675419474844;5421.972890135524;5437.456254374689;5317.308749862648;5321.760520776497;5322.574194064543;5330.173823057432;5344.893102138013 16:38:21 INFO - PROCESS | 8290 | |5;yui.html;759.4000739415861;762.249184553215;775.0234704272615;843.1147295339891;854.5300084953276;16040;16863.747043794352;16907.577310567227;16911.323547058135;16923.830761692378;15667.29497172627;15789.28948197331;15812.84187158128;15819.920900395511;15828.446004389849;652.2531701202121;793.7102957420528;796.34699546372;820.3888103363003;821.4906757810166;808.2765924497571;818.9262966333029;819.3895547816885;819.573821612761;820.1798201798201;874.6501399440243;875.829213303406;876.5617191404298;877.0089798527687;877.1097336203726;684.1482354468668;828.668532586964;849.8045449546577;872.720729366603;872.8952525696925;628.5160426471637;628.7359309090193;629.4555209743539;629.6820105846564;629.968501574924;15470.5358839235;15617.921910390376;15629.140397278145;15660.216989150496;15664.686706265924;14714.852851471454;15153;15292.694146117126;15305.693886122215;15392.30734616945;13515.526956556572;13547.661308467268;13559.59321220355;13842.169469831842;14533.92733036328;4500.842470513517;4503.301988191835;4559.908801823978;4588.816447342102;4590.839320623762;83507.58246208791;84869.57565212196;85797;86728.56635716844;90126;11344.886551134548;11512.712182195344;11831.763364732657;11851.822222666668;12012.03598820359;21490.14039438405;21622.567548649255;21646;21794;21852;8452.8732069019;8551.914480855112;8623.267022303049;8668.826623467496;8720.607572659246;4700.2009658358165;4701.129647639737;4740.336352910595;4779.283107533828;4860.052289803454;3366.4781958796425;3460.7750496217664;3481.1819222482695;3484.6409900138965;3488.1977145256224;592.1147883913577;594.1889321076699;597.692188522911;602.172013481463;615.7167702856647;561.8876224754983;639.9136116624196;671.6473851228046;675.790504943469;710.0520804725749;8928.866067009;9746.366486178376;9748.122668959828;9902;10247.948760256151;664.2759392262457;665.311402698205;685.7188552693372;705.1634404448921;713.1869668577825;4614.377059097046;4881.341018962466;4936.753162341868;4939.60396039604;4947.233178857283;103601;129518;130997;135908;141499;87256;88254;89758.55120724355;102856.48571757093;103824.48087759512;55931.16103258455;58906.11640825391;86698.56650716707;89077.55461222652;91945.54027229821;10055.446950417798;10631.149508039493;10868.67393978182;10960.725981850517;11049.226554141133;51217.48782512127;52154.478455214965;52808;52971.73514132559;54090.72954635201;75937.62031189809;79570;80119.59940200261;80338.59830701043;81569;12829.61511154667;13110.934445327712;13156.276404797445;13279.203247805162;13370.532031378854;14112.858871411154;14184.574462766133;14338.641533961734;14345.64135896611;15022.92488537594;8042.922178619493;13344.919188659025;14203.431862725376;14420.206888621227;14988.100713957198;14060.718785624435;14498.546872055123;14577.959085727794;14776.187309698069;14957.626059348604;13561.254131022888;14254.287285635884;14470.421183152555;15148.16685082331;15180.392784288946;11076.889231107585;11161.776764464828;11431.59989400367;11501.712457188638;11504.823373936484;12002.279863208236;12050.47851286068;12100;12187;12383.814242786546 16:38:21 INFO - PROCESS | 8290 | __end_tp_report 16:38:21 INFO - PROCESS | 8290 | __start_cc_report 16:38:21 INFO - PROCESS | 8290 | _x_x_mozilla_cycle_collect,1058 16:38:21 INFO - PROCESS | 8290 | __end_cc_report 16:38:21 INFO - PROCESS | 8290 | __startTimestamp1470181101956__endTimestamp 16:38:21 INFO - PROCESS | 8290 | 16:38:21 INFO - PROCESS | 8290 | ------- Summary: start ------- 16:38:21 INFO - PROCESS | 8290 | Number of tests: 6 16:38:21 INFO - PROCESS | 8290 | 16:38:21 INFO - PROCESS | 8290 | [#0] dojo.html Cycles:165 Average:5333.38 Median:5503.67 stddev:610.38 (11.1%) stddev-sans-first:585.39 16:38:21 INFO - PROCESS | 8290 | Values: 3050.6 3050.7 3077.9 3092.9 3101.8 5196.1 5218.8 5344.8 5398.5 5438.5 5365.2 5389.5 5407.1 5426.2 5444.1 4827.2 4911.1 4917.7 4932.6 5058.1 4949.6 4971.1 4997.8 5007.5 5010.8 4734.3 4762.9 4849.2 4885.2 4962.9 4832.8 4844.7 4851.1 4852.4 4943.9 4828.2 4847.8 4855.7 4874.0 4915.5 5160.7 5308.1 5338.3 5362.5 5385.0 5759.1 5796.4 5801.6 5806.0 5810.8 5788.1 5794.8 5828.0 5835.5 5845.0 5133.4 5327.7 5370.3 5379.3 5444.5 5924.5 5979.8 5990.0 5993.8 5993.9 5681.4 5683.5 5702.4 5710.4 5734.9 5431.6 5649.1 5692.1 5696.5 5795.6 5712.0 5716.1 5721.1 5721.5 5724.5 5670.8 5696.9 5731.7 5733.3 5765.2 4289.6 4339.2 4403.3 4413.7 4458.6 3935.9 3936.1 3936.1 3973.0 3973.5 5426.2 5435.8 5437.2 5454.6 5503.7 5514.8 5707.9 5747.8 5757.9 5844.2 5313.4 5363.6 5363.8 5368.2 5447.4 4964.2 4978.2 4978.9 4983.5 5019.9 5752.3 5884.5 5973.3 5976.9 5998.3 5692.2 5705.5 5706.1 5706.8 5758.9 5996.3 5999.4 6003.6 6008.3 6009.8 5266.2 5584.1 5594.1 5636.3 5726.1 5743.9 5767.3 5771.4 5775.6 5778.5 5632.5 5643.0 5645.8 5648.1 5654.2 5434.3 5548.3 5587.7 5608.9 5704.2 5534.8 5574.0 5587.5 5589.1 5590.1 5644.8 5667.6 5696.4 5717.2 5720.7 5276.8 5311.9 5427.8 5439.7 5481.8 16:38:21 INFO - PROCESS | 8290 | 16:38:21 INFO - PROCESS | 8290 | [#1] ext.html Cycles:180 Average:63183.52 Median:44188.78 stddev:123620.00 (279.8%) stddev-sans-first:123877.41 16:38:21 INFO - PROCESS | 8290 | Values: 571.4 586.7 610.8 619.2 632.3 33392.7 34076.7 36261.1 46074.3 47255.3 47109.5 47754.8 47766.3 47784.3 47801.0 9109.3 9267.3 9268.7 9269.6 9274.2 9056.5 9143.0 9156.1 9161.1 9161.5 9202.8 9238.8 9251.6 9254.2 9256.3 9136.0 9159.2 9171.3 9173.4 9186.2 9103.6 9172.3 9175.8 9178.0 9185.5 43574.8 44284.3 44337.6 44341.3 44356.6 3542.5 3636.3 3649.8 3661.9 3984.6 33760.5 33798.7 34032.0 34178.7 35805.3 4295.4 4356.5 4398.6 4497.3 4502.3 371815.0 372825.0 375275.0 375754.0 376327.0 62090.7 62508.7 62524.0 62541.0 62663.4 64837.7 64920.7 65133.7 65232.0 65284.7 14003.6 14215.5 14240.9 14243.5 14250.7 10575.9 10602.6 10681.6 10687.3 10690.7 15677.7 17801.6 17854.3 17872.6 17911.7 5830.4 5925.9 5954.7 6016.8 6074.8 979.7 1113.4 1121.3 1127.0 1147.0 34277.0 34332.7 34725.8 34833.1 35189.1 1200.9 1209.3 1220.5 1222.7 1274.9 17613.7 17768.6 17933.0 17963.4 17964.5 570157.0 576146.0 576839.0 576923.0 577308.0 62314.1 62518.4 62655.4 62686.0 62722.0 1194.8 1331.1 1341.3 1356.6 1383.6 33638.7 35564.6 35632.1 35778.5 36027.0 54046.7 54622.5 54667.2 54692.0 54744.7 422158.0 424886.0 425511.0 426329.0 427553.0 36442.8 43186.8 43679.6 44512.3 44668.8 50058.7 50160.7 50889.7 51254.7 51644.7 42529.0 43009.1 43981.0 44151.6 45367.0 43231.6 43328.6 44226.0 44686.0 45132.8 43685.8 44634.0 44716.1 44721.3 44962.8 37173.3 38532.6 39470.6 40067.6 40326.2 29722.4 29746.9 29900.3 30174.7 30264.0 16:38:21 INFO - PROCESS | 8290 | 16:38:21 INFO - PROCESS | 8290 | [#2] jquery.html Cycles:180 Average:28911.94 Median:23646.20 stddev:81491.31 (344.6%) stddev-sans-first:81691.78 16:38:21 INFO - PROCESS | 8290 | Values: 392.4 393.5 395.0 403.1 424.9 13492.7 18716.2 19647.2 20291.4 20372.9 14654.9 18854.0 19597.9 19669.8 19702.3 5665.2 5833.8 5835.6 5843.1 5860.9 5715.7 5800.6 5802.9 5803.2 5804.4 5545.3 5630.3 5664.0 5687.2 5703.7 5563.7 5627.7 5639.5 5645.5 5645.8 4863.9 4989.4 4994.1 4995.7 4997.1 22798.9 22868.1 23446.3 23596.6 23695.8 2387.8 2510.6 2510.9 2675.4 2903.3 14692.3 14834.9 14980.6 15068.7 15247.8 1955.2 1961.5 2002.5 2006.4 2044.9 80716.0 80745.2 82264.6 82652.6 83336.6 10670.9 10904.5 11141.2 11194.9 11246.3 15471.5 15488.1 15496.5 15523.3 15525.1 7343.2 7633.7 7712.5 7893.6 7900.3 7209.6 7235.4 7419.8 7431.2 7432.6 5845.5 6340.8 6635.8 6796.8 6879.6 1915.0 1977.4 1980.3 1994.4 2004.1 926.5 1078.0 1117.4 1139.1 1152.9 20059.5 20656.0 20669.5 20733.8 20984.2 1007.7 1034.0 1055.8 1057.2 1079.1 10866.0 11623.4 11641.9 11667.4 11732.8 487701.0 490005.0 496022.0 501083.0 506842.0 75977.0 76283.6 78525.6 79395.0 81518.2 992.8 994.4 1024.4 1026.5 1028.9 16988.7 17899.7 18098.6 18197.0 18369.0 40078.2 40411.6 40516.8 40590.2 41984.8 54090.5 54419.0 54834.7 55675.7 55957.0 8346.3 8666.2 8720.7 8919.1 8962.6 12193.9 13113.4 13263.9 13509.7 13786.9 11320.0 11390.1 12065.3 12139.3 12413.2 11141.7 11394.0 11895.3 11901.9 11903.4 11504.5 11679.5 11773.8 11943.8 12117.8 8540.5 8616.4 8629.5 8856.3 8942.7 10881.9 11207.7 11291.2 11390.2 11600.2 16:38:21 INFO - PROCESS | 8290 | 16:38:21 INFO - PROCESS | 8290 | [#3] mootools.html Cycles:180 Average:6049.34 Median:4466.22 stddev:3485.56 (78.0%) stddev-sans-first:3477.36 16:38:21 INFO - PROCESS | 8290 | Values: 1338.0 1475.4 1500.6 1646.3 1794.5 7616.5 8773.1 9060.1 9064.5 9330.3 9129.3 9154.1 9203.9 9208.8 9223.5 2376.3 2402.0 2402.0 2403.0 2407.8 2398.8 2420.3 2448.9 2454.8 2459.9 2411.7 2411.8 2423.8 2441.2 2441.4 2329.7 2352.2 2353.1 2358.3 2360.4 2329.6 2345.0 2353.3 2353.6 2359.3 9208.9 9227.6 9280.7 9284.9 9311.3 5364.4 5571.4 5578.8 5591.9 5604.9 3361.4 3378.9 3397.1 3428.6 3434.6 1222.2 1229.1 1236.3 1237.9 2112.5 12793.0 12983.0 12985.3 12986.9 12992.1 4679.6 4741.5 4748.8 4786.7 5044.8 10173.5 10220.7 10263.1 10263.3 10266.5 5480.6 5510.7 5512.0 5512.9 5516.4 4109.5 4327.2 4483.8 4906.2 5144.4 3927.4 3950.6 4040.2 4066.2 4067.8 1183.9 1210.5 1216.8 1217.5 1224.1 877.6 926.7 1043.1 1043.4 1061.1 6115.8 6183.2 6487.8 6544.5 6569.7 449.1 462.0 464.1 466.0 1036.4 4276.7 4305.7 4339.0 4379.3 4448.6 11239.1 11630.7 11671.0 11673.8 11695.2 11499.4 11554.8 11558.4 11559.9 11562.4 8194.7 8437.2 8513.9 8638.6 8687.3 5959.8 6025.2 6217.3 6283.5 6429.5 10658.8 10688.7 10855.2 10903.6 10906.2 10608.7 10620.5 10915.6 10961.0 11003.7 8224.6 8260.8 8275.1 8374.1 8393.0 8967.7 9015.6 9031.0 9044.5 9047.6 7900.4 7996.1 8060.7 8119.8 8307.0 7427.3 7432.7 7577.5 7674.6 7928.3 7370.7 7488.7 7524.6 7527.4 7530.1 5282.9 5423.0 5425.9 5446.8 5551.7 5636.3 5710.1 5726.3 5799.4 5827.2 16:38:21 INFO - PROCESS | 8290 | 16:38:21 INFO - PROCESS | 8290 | [#4] prototype.html Cycles:180 Average:4533.17 Median:5212.04 stddev:1316.78 (25.3%) stddev-sans-first:1281.96 16:38:21 INFO - PROCESS | 8290 | Values: 321.5 327.0 330.5 337.2 338.5 5195.6 5216.7 5220.6 5227.0 5230.3 5157.3 5160.0 5190.3 5258.7 5261.2 3892.6 3936.6 4056.7 4081.1 4107.0 4087.7 4091.9 4096.2 4107.2 4108.3 4054.8 4062.5 4086.9 4097.6 4118.7 3885.6 3909.3 4044.7 4049.3 4061.7 3554.2 3558.6 3562.9 3568.3 3569.4 5087.6 5087.8 5120.2 5121.9 5140.7 4380.4 4521.5 4546.3 4549.5 4581.2 5347.3 5348.1 5361.5 5363.7 5385.9 3849.7 3862.7 3871.7 3886.7 3892.4 5478.2 5514.0 5535.3 5545.9 5632.5 3926.9 3933.5 3936.7 3959.1 3976.1 4006.2 4019.7 4021.6 4025.3 4033.3 4467.0 4587.2 4587.7 4598.4 4604.4 5351.4 5355.3 5357.9 5384.6 5428.4 1369.5 1390.6 1391.3 1402.8 1404.3 908.2 910.1 922.3 933.2 962.2 5120.8 5135.4 5156.9 5159.5 5194.5 5415.2 5425.7 5434.9 5439.8 5454.2 5022.9 5143.7 5145.4 5164.5 5178.7 2889.4 2900.7 2912.9 2914.8 2916.6 5651.6 5662.8 5681.5 5685.2 5705.6 5325.3 5482.5 5491.6 5551.5 5551.7 5631.3 5637.3 5640.8 5644.7 5650.9 5273.9 5414.1 5426.6 5428.9 5450.9 5388.3 5409.2 5412.5 5426.5 5434.6 5593.9 5616.7 5632.5 5638.8 5651.9 3878.3 3964.5 3973.6 4015.2 4056.0 5449.7 5453.4 5466.3 5472.6 5479.5 5333.6 5356.3 5420.1 5432.3 5432.5 5205.3 5303.9 5382.5 5407.9 5434.3 5275.6 5290.2 5331.7 5333.0 5339.3 5207.4 5360.0 5409.7 5422.0 5437.5 5317.3 5321.8 5322.6 5330.2 5344.9 16:38:21 INFO - PROCESS | 8290 | 16:38:21 INFO - PROCESS | 8290 | [#5] yui.html Cycles:180 Average:21513.74 Median:4589.83 stddev:31264.05 (681.2%) stddev-sans-first:31312.92 16:38:21 INFO - PROCESS | 8290 | Values: 759.4 762.2 775.0 843.1 854.5 16040.0 16863.7 16907.6 16911.3 16923.8 15667.3 15789.3 15812.8 15819.9 15828.4 652.3 793.7 796.3 820.4 821.5 808.3 818.9 819.4 819.6 820.2 874.7 875.8 876.6 877.0 877.1 684.1 828.7 849.8 872.7 872.9 628.5 628.7 629.5 629.7 630.0 15470.5 15617.9 15629.1 15660.2 15664.7 14714.9 15153.0 15292.7 15305.7 15392.3 13515.5 13547.7 13559.6 13842.2 14533.9 4500.8 4503.3 4559.9 4588.8 4590.8 83507.6 84869.6 85797.0 86728.6 90126.0 11344.9 11512.7 11831.8 11851.8 12012.0 21490.1 21622.6 21646.0 21794.0 21852.0 8452.9 8551.9 8623.3 8668.8 8720.6 4700.2 4701.1 4740.3 4779.3 4860.1 3366.5 3460.8 3481.2 3484.6 3488.2 592.1 594.2 597.7 602.2 615.7 561.9 639.9 671.6 675.8 710.1 8928.9 9746.4 9748.1 9902.0 10247.9 664.3 665.3 685.7 705.2 713.2 4614.4 4881.3 4936.8 4939.6 4947.2 103601.0 129518.0 130997.0 135908.0 141499.0 87256.0 88254.0 89758.6 102856.5 103824.5 55931.2 58906.1 86698.6 89077.6 91945.5 10055.4 10631.1 10868.7 10960.7 11049.2 51217.5 52154.5 52808.0 52971.7 54090.7 75937.6 79570.0 80119.6 80338.6 81569.0 12829.6 13110.9 13156.3 13279.2 13370.5 14112.9 14184.6 14338.6 14345.6 15022.9 8042.9 13344.9 14203.4 14420.2 14988.1 14060.7 14498.5 14578.0 14776.2 14957.6 13561.3 14254.3 14470.4 15148.2 15180.4 11076.9 11161.8 11431.6 11501.7 11504.8 12002.3 12050.5 12100.0 12187.0 12383.8 16:38:21 INFO - PROCESS | 8290 | -------- Summary: end -------- 16:38:21 INFO - PROCESS | 8290 | 16:38:22 INFO - TEST-INFO | 8290: exit 0 16:38:22 INFO - TEST-OK | dromaeo_css | took 1114802ms 16:38:22 INFO - TEST-START | kraken 16:38:22 INFO - operating with platform_type : linux_ 16:38:22 INFO - Initialising browser for kraken test... 16:38:22 INFO - TEST-INFO | started process 8479 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaYH5ED/profile http://localhost:58327/getInfo.html) 16:38:26 INFO - PROCESS | 8479 | __metrics Screen width/height:1600/1200 16:38:26 INFO - PROCESS | 8479 | colorDepth:24 16:38:26 INFO - PROCESS | 8479 | Browser inner width/height: 1024/697 16:38:26 INFO - PROCESS | 8479 | __metrics 16:38:27 INFO - PROCESS | 8479 | 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" 16:38:27 INFO - PROCESS | 8479 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:38:27 INFO - PROCESS | 8479 | console.error: PushService: 16:38:27 INFO - PROCESS | 8479 | stateChangeProcessEnqueue: Error transitioning state 16:38:27 INFO - PROCESS | 8479 | UnknownError 16:38:28 INFO - TEST-INFO | 8479: exit 0 16:38:28 INFO - Browser initialized. 16:38:28 INFO - Running cycle 1/1 for kraken test... 16:38:28 INFO - TEST-INFO | started process 8555 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaYH5ED/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:40 INFO - PROCESS | 8555 | RSS: Main: 156446720 16:38:40 INFO - PROCESS | 8555 | 16:38:42 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58327/tests/kraken/driver.html?testName=audio-beat-detection) 16:38:43 INFO - PROCESS | 8555 | RSS: Main: 212578304 16:38:43 INFO - PROCESS | 8555 | 16:38:46 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58327/tests/kraken/driver.html?testName=audio-dft) 16:38:46 INFO - PROCESS | 8555 | RSS: Main: 190840832 16:38:46 INFO - PROCESS | 8555 | 16:38:50 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58327/tests/kraken/driver.html?testName=audio-fft) 16:38:50 INFO - PROCESS | 8555 | RSS: Main: 269881344 16:38:50 INFO - PROCESS | 8555 | 16:38:52 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58327/tests/kraken/driver.html?testName=audio-oscillator) 16:38:53 INFO - PROCESS | 8555 | RSS: Main: 178937856 16:38:53 INFO - PROCESS | 8555 | 16:38:55 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58327/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:38:56 INFO - PROCESS | 8555 | RSS: Main: 183902208 16:38:56 INFO - PROCESS | 8555 | 16:38:59 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58327/tests/kraken/driver.html?testName=imaging-darkroom) 16:38:59 INFO - PROCESS | 8555 | RSS: Main: 218808320 16:38:59 INFO - PROCESS | 8555 | 16:39:03 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58327/tests/kraken/driver.html?testName=imaging-desaturate) 16:39:03 INFO - PROCESS | 8555 | RSS: Main: 219594752 16:39:03 INFO - PROCESS | 8555 | 16:39:06 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58327/tests/kraken/driver.html?testName=json-parse-financial) 16:39:07 INFO - PROCESS | 8555 | RSS: Main: 219447296 16:39:07 INFO - PROCESS | 8555 | 16:39:08 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58327/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:39:09 INFO - PROCESS | 8555 | RSS: Main: 183566336 16:39:09 INFO - PROCESS | 8555 | 16:39:10 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58327/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:39:11 INFO - PROCESS | 8555 | RSS: Main: 189419520 16:39:11 INFO - PROCESS | 8555 | 16:39:13 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58327/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:39:14 INFO - PROCESS | 8555 | RSS: Main: 193798144 16:39:14 INFO - PROCESS | 8555 | 16:39:16 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58327/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:39:17 INFO - PROCESS | 8555 | RSS: Main: 189927424 16:39:17 INFO - PROCESS | 8555 | 16:39:20 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58327/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:39:20 INFO - PROCESS | 8555 | RSS: Main: 193003520 16:39:20 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | Cycle 1(1): loaded http://localhost:58327/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58327/tests/kraken/driver.html?testName=ai-astar) 16:39:22 INFO - PROCESS | 8555 | RSS: Main: 243707904 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | __start_tp_report 16:39:22 INFO - PROCESS | 8555 | _x_x_mozilla_page_load 16:39:22 INFO - PROCESS | 8555 | _x_x_mozilla_page_load_details 16:39:22 INFO - PROCESS | 8555 | |i|pagename|runs| 16:39:22 INFO - PROCESS | 8555 | |0;ai-astar;103;112;111;112;112;112;110;111;111;113 16:39:22 INFO - PROCESS | 8555 | |1;audio-beat-detection;145;121;121;167;120;119;146;118;164;122 16:39:22 INFO - PROCESS | 8555 | |2;audio-dft;160;161;160;161;161;161;161;161;161;161 16:39:22 INFO - PROCESS | 8555 | |3;audio-fft;76;75;74;125;74;74;73;74;100;74 16:39:22 INFO - PROCESS | 8555 | |4;audio-oscillator;84;84;114;84;84;83;119;84;83;131 16:39:22 INFO - PROCESS | 8555 | |5;imaging-gaussian-blur;117;117;117;117;117;118;117;117;117;117 16:39:22 INFO - PROCESS | 8555 | |6;imaging-darkroom;145;145;146;146;146;145;146;146;148;145 16:39:22 INFO - PROCESS | 8555 | |7;imaging-desaturate;94;95;96;96;96;95;95;95;94;95 16:39:22 INFO - PROCESS | 8555 | |8;json-parse-financial;50;49;49;49;49;49;51;49;49;48 16:39:22 INFO - PROCESS | 8555 | |9;json-stringify-tinderbox;61;63;62;62;63;61;61;62;62;62 16:39:22 INFO - PROCESS | 8555 | |10;stanford-crypto-aes;79;80;76;76;75;76;75;75;75;75 16:39:22 INFO - PROCESS | 8555 | |11;stanford-crypto-ccm;123;130;121;130;121;123;120;129;164;123 16:39:22 INFO - PROCESS | 8555 | |12;stanford-crypto-pbkdf2;158;158;160;156;162;159;157;159;159;156 16:39:22 INFO - PROCESS | 8555 | |13;stanford-crypto-sha256-iterative;64;63;64;63;62;63;63;62;63;63 16:39:22 INFO - PROCESS | 8555 | __end_tp_report 16:39:22 INFO - PROCESS | 8555 | __start_cc_report 16:39:22 INFO - PROCESS | 8555 | _x_x_mozilla_cycle_collect,1699 16:39:22 INFO - PROCESS | 8555 | __end_cc_report 16:39:22 INFO - PROCESS | 8555 | __startTimestamp1470181162496__endTimestamp 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | ------- Summary: start ------- 16:39:22 INFO - PROCESS | 8555 | Number of tests: 14 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:2.83 (2.5%) stddev-sans-first:0.88 16:39:22 INFO - PROCESS | 8555 | Values: 103.0 112.0 111.0 112.0 112.0 112.0 110.0 111.0 111.0 113.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:133.50 stddev:19.47 (14.6%) stddev-sans-first:20.26 16:39:22 INFO - PROCESS | 8555 | Values: 145.0 121.0 121.0 167.0 120.0 119.0 146.0 118.0 164.0 122.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 16:39:22 INFO - PROCESS | 8555 | Values: 160.0 161.0 160.0 161.0 161.0 161.0 161.0 161.0 161.0 161.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#3] audio-fft Cycles:10 Average:81.90 Median:74.00 stddev:17.19 (23.2%) stddev-sans-first:18.10 16:39:22 INFO - PROCESS | 8555 | Values: 76.0 75.0 74.0 125.0 74.0 74.0 73.0 74.0 100.0 74.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:84.00 stddev:18.64 (22.2%) stddev-sans-first:19.34 16:39:22 INFO - PROCESS | 8555 | Values: 84.0 84.0 114.0 84.0 84.0 83.0 119.0 84.0 83.0 131.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:39:22 INFO - PROCESS | 8555 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 16:39:22 INFO - PROCESS | 8555 | Values: 145.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 148.0 145.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 16:39:22 INFO - PROCESS | 8555 | Values: 94.0 95.0 96.0 96.0 96.0 95.0 95.0 95.0 94.0 95.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#8] json-parse-financial Cycles:10 Average:49.20 Median:49.00 stddev:0.79 (1.6%) stddev-sans-first:0.78 16:39:22 INFO - PROCESS | 8555 | Values: 50.0 49.0 49.0 49.0 49.0 49.0 51.0 49.0 49.0 48.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 16:39:22 INFO - PROCESS | 8555 | Values: 61.0 63.0 62.0 62.0 63.0 61.0 61.0 62.0 62.0 62.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:76.00 stddev:1.81 (2.4%) stddev-sans-first:1.62 16:39:22 INFO - PROCESS | 8555 | Values: 79.0 80.0 76.0 76.0 75.0 76.0 75.0 75.0 75.0 75.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#11] stanford-crypto-ccm Cycles:10 Average:128.40 Median:126.00 stddev:13.08 (10.4%) stddev-sans-first:13.73 16:39:22 INFO - PROCESS | 8555 | Values: 123.0 130.0 121.0 130.0 121.0 123.0 120.0 129.0 164.0 123.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.40 Median:159.00 stddev:1.84 (1.2%) stddev-sans-first:1.94 16:39:22 INFO - PROCESS | 8555 | Values: 158.0 158.0 160.0 156.0 162.0 159.0 157.0 159.0 159.0 156.0 16:39:22 INFO - PROCESS | 8555 | 16:39:22 INFO - PROCESS | 8555 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 16:39:22 INFO - PROCESS | 8555 | Values: 64.0 63.0 64.0 63.0 62.0 63.0 63.0 62.0 63.0 63.0 16:39:22 INFO - PROCESS | 8555 | -------- Summary: end -------- 16:39:22 INFO - PROCESS | 8555 | 16:39:23 INFO - TEST-INFO | 8555: exit 0 16:39:23 INFO - TEST-OK | kraken | took 60790ms 16:39:23 INFO - SUITE-END | took 1175s 16:39:23 INFO - Completed test suite (00:19:35) 16:39:23 INFO - javascript benchmark 16:39:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3050.557669137974, 3050.6796786337436, 3077.907662770116, 3092.9381412371768, 3101.798383105098, 5196.090684130281, 5218.843434696955, 5344.812931547392, 5398.48714372135, 5438.456154384559, 5365.168398898168, 5389.51494365507, 5407.134858422653, 5426.240326354314, 5444.074507333753, 4827.155247831633, 4911.091448082117, 4917.680350777206, 4932.556069953704, 5058.064258112261, 4949.604031677475, 4971.080350135237, 4997.825076122336, 5007.524285192901, 5010.799568017275, 4734.337192793029, 4762.857114286577, 4849.224124140156, 4885.2427873679635, 4962.875928101808, 4832.830850920201, 4844.66087373884, 4851.078295123951, 4852.417709874802, 4943.851684449436, 4828.155072862271, 4847.75761211938, 4855.732934688591, 4874.000829829876, 4915.5330243626795, 5160.664556803796, 5308.124159513675, 5338.252644629755, 5362.544183744387, 5384.973075134639, 5759.136129580555, 5796.391378905197, 5801.622894511843, 5806.012977793743, 5810.82567522975, 5788.102844059177, 5794.797182098649, 5828.0, 5835.4748072673665, 5845.035569131088, 5133.358330208724, 5327.653702509325, 5370.3287089113865, 5379.300690910157, 5444.455554444603, 5924.496417804535, 5979.760809567656, 5990.0, 5993.760249589967, 5993.850153746192, 5681.431856814368, 5683.488486036276, 5702.429757024264, 5710.371859095496, 5734.913976290359, 5431.646942948775, 5649.12438572022, 5692.117721753135, 5696.544276457874, 5795.623284486579, 5712.0, 5716.114002329646, 5721.141828725725, 5721.485066344049, 5724.513416359656, 5670.829875103754, 5696.857578560569, 5731.684757338387, 5733.254676892061, 5765.164051212634, 4289.592488713567, 4339.240632889232, 4403.3174857897075, 4413.668974826901, 4458.621017213508, 3935.8819235422984, 3936.0553467167674, 3936.0553467168247, 3973.0, 3973.4834471518516, 5426.240326354314, 5435.809746658927, 5437.184422336682, 5454.563634909197, 5503.669779813144, 5514.751836167382, 5707.942920570741, 5747.798827041033, 5757.913631295534, 5844.240248767718, 5313.44208858072, 5363.624546281801, 5363.785448582013, 5368.1679339702405, 5447.373552041487, 4964.15609346412, 4978.178600530872, 4978.850634480971, 4983.476734942851, 5019.874503137451, 5752.338481074647, 5884.470397664145, 5973.342932277446, 5976.850578735566, 5998.3401825798255, 5692.231548740867, 5705.486506214461, 5706.144078388275, 5706.80026199081, 5758.8848223034465, 5996.280446346466, 5999.430054144849, 6003.639781612943, 6008.309044459768, 6009.849753756191, 5266.236395722675, 5584.078627026495, 5594.0769772987005, 5636.323641163087, 5726.0838265877255, 5743.9138412923, 5767.3367562729145, 5771.394003629726, 5775.595708300547, 5778.479936805707, 5632.493075623213, 5643.040683083967, 5645.774169033192, 5648.096304591246, 5654.236678048575, 5434.2935418395355, 5548.250986116984, 5587.664740115606, 5608.8878222434505, 5704.172894930295, 5534.7786088556, 5574.0, 5587.497125258747, 5589.07780216276, 5590.077637189818, 5644.8024319148635, 5667.603267771216, 5696.401877802771, 5717.22817419643, 5720.656760594377, 5276.841694749163, 5311.893762124813, 5427.782888684408, 5439.728013599383, 5481.808136715196], "value": 5291.908602794554, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.3714913595046, 586.7476984896495, 610.8320211941718, 619.2197830733276, 632.2602555010637, 33392.66607333929, 34076.65923340769, 36261.0934726632, 46074.30888536674, 47255.29117063238, 47109.52890471094, 47754.76122619391, 47766.28350574736, 47784.28323575149, 47801.0, 9109.271258299337, 9267.304952128618, 9268.72193834186, 9269.629214831433, 9274.165325120737, 9056.501892395916, 9143.03998080202, 9156.084391560857, 9161.129692679218, 9161.496117713523, 9202.76993075175, 9238.769030774218, 9251.58367873444, 9254.167124958753, 9256.25949924008, 9135.95432022839, 9159.221466175382, 9171.266298696088, 9173.403728757676, 9186.173244408003, 9103.63585456581, 9172.266218702487, 9175.770605734844, 9178.0, 9185.540722963826, 43574.782126089485, 44284.335734964, 44337.556624433666, 44341.33487997683, 44356.556434435566, 3542.5217595624517, 3636.2909232699635, 3649.835757390924, 3661.8901432957055, 3984.561698213194, 33760.49359259613, 33798.6620133798, 34032.0, 34178.658213417795, 35805.28389432197, 4295.355696645497, 4356.455443069616, 4398.582134697199, 4497.308646499275, 4502.302143167782, 371815.0, 372825.0, 375275.0, 375754.0, 376327.0, 62090.68954655198, 62508.687456562424, 62524.0, 62541.0, 62663.373366266664, 64837.67581162064, 64920.67539662366, 65133.67433162899, 65232.0, 65284.673576631816, 14003.649908752363, 14215.50245741394, 14240.928795355956, 14243.501477448446, 14250.714985700228, 10575.947120264349, 10602.628907988183, 10681.626143084955, 10687.30532515384, 10690.732731681615, 15677.686446271011, 17801.554961126076, 17854.285828566713, 17872.642547148986, 17911.73132403015, 5830.416958304136, 5925.940740592625, 5954.732037058343, 6016.819495415145, 6074.757009719562, 979.6522234606755, 1113.3987646670869, 1121.327203677803, 1127.025123268367, 1147.0307590086363, 34277.0, 34332.65667343345, 34725.82637086798, 34833.10302165597, 35189.12027199289, 1200.9256303862142, 1209.3469526455792, 1220.5117952818766, 1222.706550427891, 1274.8725127487362, 17613.7357939631, 17768.644627107384, 17933.0, 17963.371282005068, 17964.461066168034, 570157.0, 576146.0, 576839.0, 576923.0, 577308.0, 62314.0652890207, 62518.37481625126, 62655.37344626495, 62686.0, 62722.0, 1194.75507520958, 1331.1414137881013, 1341.3494455189225, 1356.6133651909158, 1383.6471699716305, 33638.663613363555, 35564.64435355717, 35632.10919727028, 35778.463323049655, 36027.0, 54046.729766350916, 54622.453775461734, 54667.179992300946, 54692.0, 54744.72627636836, 422158.0, 424886.0, 425511.0, 426329.0, 427553.0, 36442.8177859109, 43186.78406607947, 43679.563204368824, 44512.33231501595, 44668.77665611651, 50058.74970625269, 50160.74919625378, 50889.745551272004, 51254.74372628113, 51644.74177629088, 42529.0, 43009.13981720286, 43981.0, 44151.558484414745, 45367.0, 43231.567684322756, 43328.56671433245, 44226.0, 44686.0, 45132.77433612811, 43685.78157109321, 44634.0, 44716.10567788691, 44721.32918006167, 44962.77518612386, 37173.256534869695, 38532.614673852906, 39470.60529394669, 40067.59932400638, 40326.1934761309, 29722.40555188841, 29746.85126574353, 29900.252493687833, 30174.698253017188, 30264.0], "value": 20857.423030980524, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.4192195550583, 393.5454549994755, 394.9684025277978, 403.1493548612427, 424.885280974137, 13492.730145397087, 18716.157772900217, 19647.214111435525, 20291.39125826224, 20372.89813550934, 14654.926725366386, 18854.0, 19597.902010489965, 19669.803301966975, 19702.310419135334, 5665.206871038058, 5833.766649334021, 5835.620684655484, 5843.094320380326, 5860.882782344351, 5715.71421428929, 5800.564957628195, 5802.883942321151, 5803.245578074844, 5804.448577385142, 5545.334559852823, 5630.268065151545, 5663.971680141593, 5687.203791469197, 5703.657780533161, 5563.749631266603, 5627.6904770237625, 5639.492445679888, 5645.520130788888, 5645.774169033254, 4863.902721945577, 4989.401271847401, 4994.051130285234, 4995.675281106753, 4997.075541024922, 22798.88600557003, 22868.085276588918, 23446.296611101694, 23596.646050309086, 23695.763042369526, 2387.7970372518357, 2510.6234064890414, 2510.8734687362776, 2675.3712877473777, 2903.3177203357195, 14692.26538673062, 14834.851651483563, 14980.625484362869, 15068.698626027528, 15247.771283430646, 1955.1529329782704, 1961.4507937777446, 2002.549426379053, 2006.4181387397637, 2044.9386518404472, 80715.99999999942, 80745.19254807495, 82264.58867705683, 82652.58673706593, 83336.58331708363, 10670.946645266724, 10904.454777261264, 11141.220114592063, 11194.888051119548, 11246.268992515625, 15471.5358539244, 15488.14815185153, 15496.457623983106, 15523.301451434487, 15525.068495890282, 7343.192248852622, 7633.6564854580565, 7712.498687585401, 7893.605319733991, 7900.288973992253, 7209.6034718091005, 7235.421166306683, 7419.814504637319, 7431.219721929119, 7432.554046757212, 5845.532357411397, 6340.778072767524, 6635.8009259722285, 6796.8300792479595, 6879.58722476653, 1914.9904250478658, 1977.4364306172672, 1980.3266889257723, 1994.3518356534184, 2004.0981558298824, 926.4533924984291, 1077.9514921828536, 1117.374220509157, 1139.0602752729033, 1152.9192956492966, 20059.49851253701, 20656.0, 20669.483262918548, 20733.792662073487, 20984.160633574487, 1007.7430255284855, 1033.9741506462399, 1055.8257887448638, 1057.1912486947492, 1079.1241341824107, 10866.0, 11623.360715160747, 11641.941790290994, 11667.416629168507, 11732.824007639892, 487701.0, 490005.0, 496022.0, 501083.0, 506842.0, 75977.0, 76283.61858190673, 78525.60737196277, 79395.0, 81518.18481815225, 992.8163289791412, 994.3586386780486, 1024.4314405504867, 1026.471367245869, 1028.922830787694, 16988.66022679515, 17899.668358172883, 18098.638027239645, 18197.0, 18369.0, 40078.198436030536, 40411.595884040784, 40516.797416012734, 40590.18819623532, 41984.80911453104, 54090.45909540854, 54419.0, 54834.72582637221, 55675.72162139163, 55957.0, 8346.290565301775, 8666.176713212235, 8720.738377848675, 8919.10808919103, 8962.596683149404, 12193.939030304791, 13113.40989655487, 13263.933680331536, 13509.662258443617, 13786.931065344608, 11320.0, 11390.088792896715, 12065.336406497727, 12139.332336721565, 12413.193142445713, 11141.665750027512, 11394.0, 11895.345755983504, 11901.940490297493, 11903.40482975865, 11504.482298296429, 11679.532818687498, 11773.76452470963, 11943.820842687193, 12117.75764484723, 8540.52759707867, 8616.439931404439, 8629.482231066157, 8856.291496680122, 8942.68700595476, 10881.945590271998, 11207.71980700489, 11291.153163512934, 11390.202685811913, 11600.245984011011], "value": 9393.83145210067, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.0232430325868, 1475.3508456279233, 1500.6398464368551, 1646.2509558151035, 1794.4796009157344, 7616.466847320689, 8773.122687731224, 9060.139286767762, 9064.546772661373, 9330.346875718702, 9129.269658427327, 9154.084591540843, 9203.907960920405, 9208.815823683539, 9223.49270790108, 2376.298991797415, 2401.987990060047, 2401.987990060056, 2403.0267741564694, 2407.759224077587, 2398.82008849337, 2420.286015625386, 2448.8897999590063, 2454.828162028659, 2459.9262022139364, 2411.6985376827897, 2411.8432301900407, 2423.781859632633, 2441.218809980807, 2441.401856545149, 2329.6971393718877, 2352.188494969233, 2353.105819788478, 2358.3043002314357, 2360.409897525619, 2329.5690297294886, 2344.9531009379716, 2353.2587235021083, 2353.611654077092, 2359.2686267257204, 9208.907910920872, 9227.630894764201, 9280.72157835266, 9284.907150928471, 9311.255099592016, 5364.409914909357, 5571.415001424833, 5578.804741834057, 5591.944080559183, 5604.887902241932, 3361.445361515347, 3378.9493157602656, 3397.065806903097, 3428.639992800745, 3434.587849458056, 1222.2422098299112, 1229.0966139887175, 1236.2767780848214, 1237.900967922564, 2112.5317904056687, 12793.0, 12983.026273029465, 12985.285809280485, 12986.935065324707, 12992.090553661248, 4679.578837904605, 4741.506425476033, 4748.762561871893, 4786.664933454695, 5044.798208071636, 10173.54219060144, 10220.744481388025, 10263.12763415118, 10263.281570290008, 10266.486675666336, 5480.588955828329, 5510.6969116698965, 5512.035393806068, 5512.91730624041, 5516.420775818561, 4109.506859176858, 4327.156204540147, 4483.820647174077, 4906.215005599159, 5144.382674079135, 3927.4108883667677, 3950.5654378018394, 4040.1717647882115, 4066.1664358806374, 4067.7762723049923, 1183.9408029598485, 1210.497643477947, 1216.7992281273491, 1217.452146534063, 1224.1186345830988, 877.5787621941504, 926.6617684545092, 1043.1341986151476, 1043.4313299251926, 1061.1033676543464, 6115.84710382244, 6183.227096612924, 6487.805365839033, 6544.509161812883, 6569.737210511622, 449.0905915521096, 462.0, 464.07880357490353, 465.9510751371057, 1036.445501656625, 4276.743395396286, 4305.720128191658, 4339.0, 4379.299312110047, 4448.621867141264, 11239.100871930226, 11630.70923226926, 11671.0, 11673.824892626617, 11695.181337306478, 11499.425028748696, 11554.76890462186, 11558.364289963965, 11559.884401156049, 11562.421878906021, 8194.713185038496, 8437.156284371637, 8513.914860851311, 8638.611262493203, 8687.34844886636, 5959.821205363846, 6025.1564780931585, 6217.285012223652, 6283.528735344776, 6429.453496452854, 10658.786824263627, 10688.679339619825, 10855.185861060294, 10903.618373356894, 10906.236563440483, 10608.734781630521, 10620.52207650642, 10915.56337746513, 10961.013508784248, 11003.669889903316, 8224.62989165477, 8260.752177434686, 8275.089740128702, 8374.07885132647, 8393.0, 8967.686130985385, 9015.639374425213, 9030.954845225995, 9044.547772611211, 9047.592858321259, 7900.367970562456, 7996.080450748006, 8060.717874874351, 8119.837603247784, 8307.0, 7427.294407031323, 7432.702691892264, 7577.5453472791805, 7674.577898215429, 7928.286454219148, 7370.6683199255085, 7488.737894173678, 7524.5861477619255, 7527.3601743850195, 7530.096388433423, 5282.867928301823, 5423.0, 5425.891482170256, 5446.836594902159, 5551.722413879209, 5636.2954630671165, 5710.086386178192, 5726.284214473191, 5799.362070172362, 5827.213326200908], "value": 4767.75998506197, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.4503199528806, 326.96566860479646, 330.4976435817557, 337.16887871397, 338.54972886061523, 5195.58435325174, 5216.66091704039, 5220.608454365928, 5227.0, 5230.267762513251, 5157.343967856556, 5160.019596276705, 5190.299309593203, 5258.658187217838, 5261.210818377245, 3892.5912779158207, 3936.5866584008695, 4056.71602987791, 4081.1429599783933, 4107.034846810997, 4087.734297270668, 4091.89770255743, 4096.221717873609, 4107.1580326033245, 4108.3221268490815, 4054.756714597119, 4062.4718786557705, 4086.8569600064, 4097.569755175693, 4118.732282401649, 3885.6308650678284, 3909.2767837949928, 4044.676425885922, 4049.250888585592, 4061.715679902408, 3554.1647712787476, 3558.62634423387, 3562.8931132066077, 3568.3398571264397, 3569.37535931211, 5087.643864929457, 5087.771050302746, 5120.15517439622, 5121.897562048775, 5140.7172605506685, 4380.408644832939, 4521.547845215452, 4546.272596384596, 4549.522300158502, 4581.221192397301, 5347.331583552056, 5348.090824559834, 5361.544268737162, 5363.7127089498235, 5385.946140538584, 3849.6727778138893, 3862.6523612874976, 3871.6515513603913, 3886.689064874803, 3892.3772196448717, 5478.150886612541, 5514.0, 5535.335759708821, 5545.894299192465, 5632.5073855428345, 3926.9410958835642, 3933.547642021147, 3936.7244292899795, 3959.089409435788, 3976.0855003348815, 4006.1587066715733, 4019.698522610816, 4021.5978402160135, 4025.2754504188943, 4033.3344998075577, 4466.977665111654, 4587.197240482902, 4587.724736515753, 4598.448186217608, 4604.447466304065, 5351.3578370595, 5355.30381050461, 5357.919631205535, 5384.596155288369, 5428.4028756836715, 1369.4935570871962, 1390.5897760160776, 1391.2973948156116, 1402.8106205662104, 1404.2767974493145, 908.1780988205685, 910.1444642036464, 922.3036607361399, 933.169479163545, 962.1965658674944, 5120.820771272987, 5135.35808023997, 5156.8968620627375, 5159.535641792257, 5194.532492075731, 5415.214793854869, 5425.674459532441, 5434.864128396822, 5439.782408703687, 5454.236406903036, 5022.924656130161, 5143.665661731976, 5145.434002259748, 5164.535191832753, 5178.71517066565, 2889.4076714273524, 2900.7244311790346, 2912.8834846606323, 2914.7668186545025, 2916.6354205724283, 5651.576131790132, 5662.801801936995, 5681.517071049007, 5685.204071430003, 5705.62913410627, 5325.334333208349, 5482.506574408322, 5491.560675145979, 5551.472610102034, 5551.694656793915, 5631.29608798892, 5637.3235211774845, 5640.830775076754, 5644.729935764502, 5650.886982260249, 5273.920891186712, 5414.133738601805, 5426.620136590401, 5428.945710542844, 5450.8909821802545, 5388.299521062237, 5409.2427060212385, 5412.535976974661, 5426.511613954763, 5434.619576629598, 5593.860153496195, 5616.66300022, 5632.521690456186, 5638.802641907513, 5651.88696226065, 3878.2825177342283, 3964.484616999887, 3973.582773808824, 4015.1768887377434, 4055.9720139930037, 5449.6730196188355, 5453.427390123979, 5466.34403871538, 5472.589555783253, 5479.479449452296, 5333.626646134733, 5356.250124982584, 5420.13277875538, 5432.266644003009, 5432.483914028161, 5205.286993259227, 5303.867403314794, 5382.461753824587, 5407.891842163056, 5434.2935418395355, 5275.577953763766, 5290.153575427913, 5331.747633943214, 5333.0, 5339.3058902342445, 5207.4271830097905, 5360.0, 5409.675419474844, 5421.972890135524, 5437.456254374689, 5317.308749862648, 5321.760520776497, 5322.574194064543, 5330.173823057432, 5344.893102138013], "value": 4115.973710788378, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.4000739415861, 762.249184553215, 775.0234704272615, 843.1147295339891, 854.5300084953276, 16040.0, 16863.747043794352, 16907.577310567227, 16911.323547058135, 16923.830761692378, 15667.29497172627, 15789.28948197331, 15812.84187158128, 15819.920900395511, 15828.446004389849, 652.2531701202121, 793.7102957420528, 796.34699546372, 820.3888103363003, 821.4906757810166, 808.2765924497571, 818.9262966333029, 819.3895547816885, 819.573821612761, 820.1798201798201, 874.6501399440243, 875.829213303406, 876.5617191404298, 877.0089798527687, 877.1097336203726, 684.1482354468668, 828.668532586964, 849.8045449546577, 872.720729366603, 872.8952525696925, 628.5160426471637, 628.7359309090193, 629.4555209743539, 629.6820105846564, 629.968501574924, 15470.5358839235, 15617.921910390376, 15629.140397278145, 15660.216989150496, 15664.686706265924, 14714.852851471454, 15153.0, 15292.694146117126, 15305.693886122215, 15392.30734616945, 13515.526956556572, 13547.661308467268, 13559.59321220355, 13842.169469831842, 14533.92733036328, 4500.842470513517, 4503.301988191835, 4559.908801823978, 4588.816447342102, 4590.839320623762, 83507.58246208791, 84869.57565212196, 85797.0, 86728.56635716844, 90126.0, 11344.886551134548, 11512.712182195344, 11831.763364732657, 11851.822222666668, 12012.03598820359, 21490.14039438405, 21622.567548649255, 21646.0, 21794.0, 21852.0, 8452.8732069019, 8551.914480855112, 8623.267022303049, 8668.826623467496, 8720.607572659246, 4700.2009658358165, 4701.129647639737, 4740.336352910595, 4779.283107533828, 4860.052289803454, 3366.4781958796425, 3460.7750496217664, 3481.1819222482695, 3484.6409900138965, 3488.1977145256224, 592.1147883913577, 594.1889321076699, 597.692188522911, 602.172013481463, 615.7167702856647, 561.8876224754983, 639.9136116624196, 671.6473851228046, 675.790504943469, 710.0520804725749, 8928.866067009, 9746.366486178376, 9748.122668959828, 9902.0, 10247.948760256151, 664.2759392262457, 665.311402698205, 685.7188552693372, 705.1634404448921, 713.1869668577825, 4614.377059097046, 4881.341018962466, 4936.753162341868, 4939.60396039604, 4947.233178857283, 103601.0, 129518.0, 130997.0, 135908.0, 141499.0, 87256.0, 88254.0, 89758.55120724355, 102856.48571757093, 103824.48087759512, 55931.16103258455, 58906.11640825391, 86698.56650716707, 89077.55461222652, 91945.54027229821, 10055.446950417798, 10631.149508039493, 10868.67393978182, 10960.725981850517, 11049.226554141133, 51217.48782512127, 52154.478455214965, 52808.0, 52971.73514132559, 54090.72954635201, 75937.62031189809, 79570.0, 80119.59940200261, 80338.59830701043, 81569.0, 12829.61511154667, 13110.934445327712, 13156.276404797445, 13279.203247805162, 13370.532031378854, 14112.858871411154, 14184.574462766133, 14338.641533961734, 14345.64135896611, 15022.92488537594, 8042.922178619493, 13344.919188659025, 14203.431862725376, 14420.206888621227, 14988.100713957198, 14060.718785624435, 14498.546872055123, 14577.959085727794, 14776.187309698069, 14957.626059348604, 13561.254131022888, 14254.287285635884, 14470.421183152555, 15148.16685082331, 15180.392784288946, 11076.889231107585, 11161.776764464828, 11431.59989400367, 11501.712457188638, 11504.823373936484, 12002.279863208236, 12050.47851286068, 12100.0, 12187.0, 12383.814242786546], "value": 7756.667643035701, "unit": "score"}], "name": "dromaeo_css", "value": 7351.413624177776, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 111.0, 112.0, 112.0, 112.0, 110.0, 111.0, 111.0, 113.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 121.0, 167.0, 120.0, 119.0, 146.0, 118.0, 164.0, 122.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 161.0, 161.0, 161.0, 161.0, 161.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 125.0, 74.0, 74.0, 73.0, 74.0, 100.0, 74.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 114.0, 84.0, 84.0, 83.0, 119.0, 84.0, 83.0, 131.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 148.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 49.0, 49.0, 49.0, 51.0, 49.0, 49.0, 48.0], "value": 49.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 62.0, 63.0, 61.0, 61.0, 62.0, 62.0, 62.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 76.0, 76.0, 75.0, 76.0, 75.0, 75.0, 75.0, 75.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 130.0, 121.0, 130.0, 121.0, 123.0, 120.0, 129.0, 164.0, 123.0], "value": 128.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 160.0, 156.0, 162.0, 159.0, 157.0, 159.0, 159.0, 156.0], "value": 158.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 63.0, 62.0, 63.0, 63.0, 62.0, 63.0, 63.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1477.8000000000004, "alertThreshold": 2.0}]} 16:39:23 INFO - Return code: 0 16:39:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:39:23 INFO - # TBPL SUCCESS # 16:39:23 INFO - Running post-action listener: _resource_record_post_action 16:39:23 INFO - [mozharness: 2016-08-02 23:39:23.775995Z] Finished run-tests step (success) 16:39:23 INFO - Running post-run listener: _resource_record_post_run 16:39:23 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 557223936; Read time: 32; Write time: 562132 16:39:23 INFO - TinderboxPrint: CPU usage
13.0% 16:39:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 32 16:39:23 INFO - TinderboxPrint: I/O write bytes / time
557,223,936 / 562,132 16:39:23 INFO - TinderboxPrint: CPU idle
8,281.0 (87.0%) 16:39:23 INFO - TinderboxPrint: CPU user
1,194.1 (12.5%) 16:39:23 INFO - TinderboxPrint: Swap in / out
0 / 0 16:39:23 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 264515584; Read time: 0; Write time: 324592 16:39:23 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 291700736; Read time: 0; Write time: 237424 16:39:24 INFO - Running post-run listener: _upload_blobber_files 16:39:24 INFO - Blob upload gear active. 16:39:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:39:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:39:24 INFO - (blobuploader) - INFO - Open directory for files ... 16:39:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:39:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:39:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:25 INFO - (blobuploader) - INFO - Done attempting. 16:39:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:39:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:39:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:26 INFO - (blobuploader) - INFO - Done attempting. 16:39:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:39:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:29 INFO - (blobuploader) - INFO - Done attempting. 16:39:29 INFO - (blobuploader) - INFO - Iteration through files over. 16:39:29 INFO - Return code: 0 16:39:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:39:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:39:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e761545505e48d40802fe57853de01ee2ea0f28904a19b8fafa74561b559ff78bd432578228ece84f6dfb7e83208f5a8a7e7880416bf5dd0272f7aeeff7b6a9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8a86156d3378a1db612e9811b8c83f6fd981c19f5d76d8deaa4f568b851c1f584f4af446103b7d4ab5a785ce9f4f03192d5baf4c48459a450a60b0c83edc647", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2bb0116a3865a79e5caecf5082e7ee5a6e7ead3523637658b424781da1a1007ced160373207e0b1bb8d49228052c99e6f3b536072132e80b8dec84127a3ecd23"} 16:39:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:39:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:39:29 INFO - Contents: 16:39:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e761545505e48d40802fe57853de01ee2ea0f28904a19b8fafa74561b559ff78bd432578228ece84f6dfb7e83208f5a8a7e7880416bf5dd0272f7aeeff7b6a9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8a86156d3378a1db612e9811b8c83f6fd981c19f5d76d8deaa4f568b851c1f584f4af446103b7d4ab5a785ce9f4f03192d5baf4c48459a450a60b0c83edc647", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2bb0116a3865a79e5caecf5082e7ee5a6e7ead3523637658b424781da1a1007ced160373207e0b1bb8d49228052c99e6f3b536072132e80b8dec84127a3ecd23"} 16:39:29 INFO - Running post-run listener: copy_logs_to_upload_dir 16:39:29 INFO - Copying logs to upload dir... 16:39:29 INFO - mkdir: /builds/slave/test/build/upload/logs 16:39:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1228.189091 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-08-02 16:39:29.507366) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 16:39:29.510706) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e761545505e48d40802fe57853de01ee2ea0f28904a19b8fafa74561b559ff78bd432578228ece84f6dfb7e83208f5a8a7e7880416bf5dd0272f7aeeff7b6a9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8a86156d3378a1db612e9811b8c83f6fd981c19f5d76d8deaa4f568b851c1f584f4af446103b7d4ab5a785ce9f4f03192d5baf4c48459a450a60b0c83edc647", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2bb0116a3865a79e5caecf5082e7ee5a6e7ead3523637658b424781da1a1007ced160373207e0b1bb8d49228052c99e6f3b536072132e80b8dec84127a3ecd23"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011309 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e761545505e48d40802fe57853de01ee2ea0f28904a19b8fafa74561b559ff78bd432578228ece84f6dfb7e83208f5a8a7e7880416bf5dd0272f7aeeff7b6a9d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8a86156d3378a1db612e9811b8c83f6fd981c19f5d76d8deaa4f568b851c1f584f4af446103b7d4ab5a785ce9f4f03192d5baf4c48459a450a60b0c83edc647", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2bb0116a3865a79e5caecf5082e7ee5a6e7ead3523637658b424781da1a1007ced160373207e0b1bb8d49228052c99e6f3b536072132e80b8dec84127a3ecd23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 16:39:29.537261) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:39:29.537682) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1470179487.286263-474823861 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004614 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:39:29.621840) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 16:39:29.622245) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 16:39:29.622629) ========= ========= Total master_lag: 0.17 =========