builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1470053336.84 results: success (0) buildid: 20160801023002 builduid: 2ba70d5b242e4024a92701940dd11911 revision: c94403616cdd5e67861c73b87e7eaec63939c375 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:56.836842) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:56.837408) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:56.837730) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:56.858475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:56.858858) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 05:08:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2016-08-01 05:08:57 (46.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.145507 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:57.028075) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:57.028430) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015160 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:57.091998) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:57.092366) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c94403616cdd5e67861c73b87e7eaec63939c375 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c94403616cdd5e67861c73b87e7eaec63939c375 --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=31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 05:08:57,297 truncating revision to first 12 chars 2016-08-01 05:08:57,297 Setting DEBUG logging. 2016-08-01 05:08:57,297 attempt 1/10 2016-08-01 05:08:57,297 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c94403616cdd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 05:08:57,630 unpacking tar archive at: fx-team-c94403616cdd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686742 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:57.798414) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:57.798786) ========= script_repo_revision: c94403616cdd5e67861c73b87e7eaec63939c375 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:57.799241) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:57.799568) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 05:08:57.810049) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-08-01 05:08:57.810488) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:08:58 INFO - MultiFileLogger online at 20160801 05:08:58 in /builds/slave/test 05:08:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:08:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:08:58 INFO - {'append_to_log': False, 05:08:58 INFO - 'base_work_dir': '/builds/slave/test', 05:08:58 INFO - 'blob_upload_branch': 'Fx-Team', 05:08:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:08:58 INFO - 'branch': 'Fx-Team', 05:08:58 INFO - 'buildbot_json_path': 'buildprops.json', 05:08:58 INFO - 'config_files': ('talos/linux_config.py',), 05:08:58 INFO - 'default_actions': ('clobber', 05:08:58 INFO - 'read-buildbot-config', 05:08:58 INFO - 'download-and-extract', 05:08:58 INFO - 'populate-webroot', 05:08:58 INFO - 'create-virtualenv', 05:08:58 INFO - 'install', 05:08:58 INFO - 'run-tests'), 05:08:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:08:58 INFO - 'download_minidump_stackwalk': True, 05:08:58 INFO - 'download_symbols': 'ondemand', 05:08:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:08:58 INFO - 'tooltool.py': '/tools/tooltool.py', 05:08:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:08:58 INFO - '/tools/misc-python/virtualenv.py')}, 05:08:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:08:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:08:58 INFO - 'installer_path': 'installer.exe', 05:08:58 INFO - 'log_level': 'info', 05:08:58 INFO - 'log_name': 'talos', 05:08:58 INFO - 'log_to_console': True, 05:08:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:08:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:08:58 INFO - 'opt_config_files': (), 05:08:58 INFO - 'pip_index': False, 05:08:58 INFO - 'sps_profile': False, 05:08:58 INFO - 'sps_profile_interval': 0, 05:08:58 INFO - 'suite': 'dromaeojs', 05:08:58 INFO - 'system_bits': '32', 05:08:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:08:58 INFO - 'title': 'talos-linux64-ix-014', 05:08:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:08:58 INFO - 'use_talos_json': True, 05:08:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:08:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:08:58 INFO - 'work_dir': 'build'} 05:08:58 INFO - [mozharness: 2016-08-01 12:08:58.103092Z] Running clobber step. 05:08:58 INFO - Running pre-action listener: _resource_record_pre_action 05:08:58 INFO - Running main action method: clobber 05:08:58 INFO - rmtree: /builds/slave/test/build 05:08:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:08:59 INFO - Running post-action listener: _resource_record_post_action 05:08:59 INFO - [mozharness: 2016-08-01 12:08:59.068804Z] Finished clobber step (success) 05:08:59 INFO - [mozharness: 2016-08-01 12:08:59.068904Z] Running read-buildbot-config step. 05:08:59 INFO - Running pre-action listener: _resource_record_pre_action 05:08:59 INFO - Running main action method: read_buildbot_config 05:08:59 INFO - Using buildbot properties: 05:08:59 INFO - { 05:08:59 INFO - "project": "", 05:08:59 INFO - "product": "firefox", 05:08:59 INFO - "script_repo_revision": "production", 05:08:59 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:08:59 INFO - "repository": "", 05:08:59 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:08:59 INFO - "buildid": "20160801023002", 05:08:59 INFO - "pgo_build": "True", 05:08:59 INFO - "basedir": "/builds/slave/test", 05:08:59 INFO - "buildnumber": 1404, 05:08:59 INFO - "slavename": "talos-linux64-ix-014", 05:08:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:08:59 INFO - "platform": "ubuntu64_hw", 05:08:59 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:08:59 INFO - "branch": "fx-team", 05:08:59 INFO - "repo_path": "integration/fx-team", 05:08:59 INFO - "revision": "c94403616cdd5e67861c73b87e7eaec63939c375", 05:08:59 INFO - "stage_platform": "linux64-pgo", 05:08:59 INFO - "builduid": "2ba70d5b242e4024a92701940dd11911", 05:08:59 INFO - "slavebuilddir": "test" 05:08:59 INFO - } 05:08:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:08:59 INFO - Running post-action listener: _resource_record_post_action 05:08:59 INFO - [mozharness: 2016-08-01 12:08:59.071355Z] Finished read-buildbot-config step (success) 05:08:59 INFO - [mozharness: 2016-08-01 12:08:59.071444Z] Running download-and-extract step. 05:08:59 INFO - Running pre-action listener: _resource_record_pre_action 05:08:59 INFO - Running main action method: download_and_extract 05:08:59 INFO - mkdir: /builds/slave/test/build/tests 05:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:59 INFO - https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:08:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:08:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:08:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:09:08 INFO - Downloaded 1361 bytes. 05:09:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:09:08 INFO - Using the following test package requirements: 05:09:08 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:09:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:09:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:09:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:08 INFO - u'jsshell-linux-x86_64.zip'], 05:09:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:09:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:09:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:09:08 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:09:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:09:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:09:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:08 INFO - https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:09:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:09:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:09:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:09:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:09:11 INFO - Downloaded 21808079 bytes. 05:09:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:09:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:09:12 INFO - Return code: 0 05:09:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:09:12 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')]} 05:09:12 INFO - https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:09:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:09:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:09:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:09:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:09:15 INFO - Downloaded 21808079 bytes. 05:09:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:09:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:09:16 INFO - Return code: 0 05:09: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')]} 05:09:16 INFO - https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:09:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:09:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:09:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:09:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:09:17 INFO - Downloaded 14413723 bytes. 05:09:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:09:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:09:17 INFO - Return code: 0 05:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:17 INFO - https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:09:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:09:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:09:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:09:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:09:19 INFO - Downloaded 59383646 bytes. 05:09:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:09:19 INFO - mkdir: /builds/slave/test/properties 05:09:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:09:19 INFO - Writing to file /builds/slave/test/properties/build_url 05:09:19 INFO - Contents: 05:09:19 INFO - build_url:https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:09:20 INFO - Running post-action listener: _resource_record_post_action 05:09:20 INFO - Running post-action listener: set_extra_try_arguments 05:09:20 INFO - [mozharness: 2016-08-01 12:09:20.338195Z] Finished download-and-extract step (success) 05:09:20 INFO - [mozharness: 2016-08-01 12:09:20.338430Z] Running populate-webroot step. 05:09:20 INFO - Running pre-action listener: _resource_record_pre_action 05:09:20 INFO - Running main action method: populate_webroot 05:09:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:09:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:09:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:09:20 INFO - '--tppagecycles', 05:09:20 INFO - '7'], 05:09:20 INFO - 'tests': ['tsvgm']}}, 05:09:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:09:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:09:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:09:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:09:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:20 INFO - 'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:20 INFO - 'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['damp', 'tps']}, 05:09:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:20 INFO - 'tests': ['damp', 'tps']}, 05:09:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['dromaeo_dom']}, 05:09:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:09:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['basic_compositor_video']}, 05:09:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:09:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['a11yr', 05:09:20 INFO - 'ts_paint', 05:09:20 INFO - 'tpaint', 05:09:20 INFO - 'sessionrestore', 05:09:20 INFO - 'sessionrestore_no_auto_restore', 05:09:20 INFO - 'tabpaint']}, 05:09:20 INFO - 'other-e10s': {'tests': ['a11yr', 05:09:20 INFO - 'ts_paint', 05:09:20 INFO - 'tpaint', 05:09:20 INFO - 'sessionrestore', 05:09:20 INFO - 'sessionrestore_no_auto_restore', 05:09:20 INFO - 'tabpaint']}, 05:09:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:09:20 INFO - 'ts_paint', 05:09:20 INFO - 'tpaint', 05:09:20 INFO - 'sessionrestore', 05:09:20 INFO - 'sessionrestore_no_auto_restore', 05:09:20 INFO - 'tabpaint']}, 05:09:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:09:20 INFO - 'ts_paint', 05:09:20 INFO - 'tpaint', 05:09:20 INFO - 'sessionrestore', 05:09:20 INFO - 'sessionrestore_no_auto_restore', 05:09:20 INFO - 'tabpaint']}, 05:09:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['a11yr', 05:09:20 INFO - 'ts_paint', 05:09:20 INFO - 'tpaint', 05:09:20 INFO - 'sessionrestore', 05:09:20 INFO - 'sessionrestore_no_auto_restore', 05:09:20 INFO - 'tabpaint']}, 05:09:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['a11yr', 05:09:20 INFO - 'ts_paint', 05:09:20 INFO - 'tpaint', 05:09:20 INFO - 'sessionrestore', 05:09:20 INFO - 'sessionrestore_no_auto_restore', 05:09:20 INFO - 'tabpaint']}, 05:09:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['tsvgx', 05:09:20 INFO - 'tsvgr_opacity', 05:09:20 INFO - 'tart', 05:09:20 INFO - 'tscrollx', 05:09:20 INFO - 'cart']}, 05:09:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:09:20 INFO - 'tsvgr_opacity', 05:09:20 INFO - 'tart', 05:09:20 INFO - 'tscrollx', 05:09:20 INFO - 'cart']}, 05:09:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:20 INFO - 'talos_options': ['--disable-e10s'], 05:09:20 INFO - 'tests': ['tp5o']}, 05:09:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:20 INFO - 'tests': ['tp5o']}, 05:09:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:20 INFO - 'talos_options': ['--disable-e10s', 05:09:20 INFO - '--xperf_path', 05:09:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:20 INFO - 'tests': ['tp5n']}, 05:09:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:20 INFO - 'talos_options': ['--xperf_path', 05:09:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:20 INFO - 'tests': ['tp5n']}}, 05:09:20 INFO - 'talos.zip': {'path': '', 05:09:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:09:20 INFO - Running post-action listener: _resource_record_post_action 05:09:20 INFO - [mozharness: 2016-08-01 12:09:20.358763Z] Finished populate-webroot step (success) 05:09:20 INFO - [mozharness: 2016-08-01 12:09:20.358853Z] Running create-virtualenv step. 05:09:20 INFO - Running pre-action listener: _resource_record_pre_action 05:09:20 INFO - Running main action method: create_virtualenv 05:09:20 INFO - Creating virtualenv /builds/slave/test/build/venv 05:09:20 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 05:09:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:09:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:09:20 INFO - Using real prefix '/usr' 05:09:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:09:20 INFO - Installing distribute.............................................................................................................................................................................................done. 05:09:22 INFO - Installing pip.................done. 05:09:22 INFO - Return code: 0 05:09:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2154180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', '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 05:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:09:22 INFO - Using env: {'DISPLAY': ':0', 05:09:22 INFO - 'HOME': '/home/cltbld', 05:09:22 INFO - 'LANG': 'en_US.UTF-8', 05:09:22 INFO - 'LANGUAGE': 'en_US:en', 05:09:22 INFO - 'LOGNAME': 'cltbld', 05:09:22 INFO - 'MAIL': '/var/mail/cltbld', 05:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:22 INFO - 'MOZ_NO_REMOTE': '1', 05:09:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:22 INFO - 'NO_EM_RESTART': '1', 05:09:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:22 INFO - 'PWD': '/builds/slave/test', 05:09:22 INFO - 'SHELL': '/bin/bash', 05:09:22 INFO - 'SHLVL': '1', 05:09:22 INFO - 'TERM': 'linux', 05:09:22 INFO - 'TMOUT': '86400', 05:09:22 INFO - 'USER': 'cltbld', 05:09:22 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:22 INFO - '_': '/tools/buildbot/bin/python'} 05:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:23 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 05:09:23 INFO - Cleaning up... 05:09:23 INFO - Return code: 0 05:09:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:09:23 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')]} 05:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:23 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')]} 05:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:23 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 0x1e93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2154180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', '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 05:09:23 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 05:09:23 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 05:09:23 INFO - Using env: {'DISPLAY': ':0', 05:09:23 INFO - 'HOME': '/home/cltbld', 05:09:23 INFO - 'LANG': 'en_US.UTF-8', 05:09:23 INFO - 'LANGUAGE': 'en_US:en', 05:09:23 INFO - 'LOGNAME': 'cltbld', 05:09:23 INFO - 'MAIL': '/var/mail/cltbld', 05:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:23 INFO - 'MOZ_NO_REMOTE': '1', 05:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:23 INFO - 'NO_EM_RESTART': '1', 05:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:23 INFO - 'PWD': '/builds/slave/test', 05:09:23 INFO - 'SHELL': '/bin/bash', 05:09:23 INFO - 'SHLVL': '1', 05:09:23 INFO - 'TERM': 'linux', 05:09:23 INFO - 'TMOUT': '86400', 05:09:23 INFO - 'USER': 'cltbld', 05:09:23 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:23 INFO - '_': '/tools/buildbot/bin/python'} 05:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:23 INFO - Downloading/unpacking psutil>=3.1.1 05:09:23 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 05:09:23 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 05:09:23 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 05:09:23 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 05:09:23 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 05:09:23 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 05:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:09:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:25 INFO - Installing collected packages: psutil 05:09:25 INFO - Running setup.py install for psutil 05:09:25 INFO - building 'psutil._psutil_linux' extension 05:09:25 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 05:09:25 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 05:09:25 INFO - building 'psutil._psutil_posix' extension 05:09:25 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 05:09:26 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 05:09:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:26 INFO - Successfully installed psutil 05:09:26 INFO - Cleaning up... 05:09:26 INFO - Return code: 0 05:09:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:09: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')]} 05:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09: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')]} 05:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:26 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 0x1e93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2154180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', '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 05:09:26 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 05:09:26 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 05:09:26 INFO - Using env: {'DISPLAY': ':0', 05:09:26 INFO - 'HOME': '/home/cltbld', 05:09:26 INFO - 'LANG': 'en_US.UTF-8', 05:09:26 INFO - 'LANGUAGE': 'en_US:en', 05:09:26 INFO - 'LOGNAME': 'cltbld', 05:09:26 INFO - 'MAIL': '/var/mail/cltbld', 05:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:26 INFO - 'MOZ_NO_REMOTE': '1', 05:09:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:26 INFO - 'NO_EM_RESTART': '1', 05:09:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:26 INFO - 'PWD': '/builds/slave/test', 05:09:26 INFO - 'SHELL': '/bin/bash', 05:09:26 INFO - 'SHLVL': '1', 05:09:26 INFO - 'TERM': 'linux', 05:09:26 INFO - 'TMOUT': '86400', 05:09:26 INFO - 'USER': 'cltbld', 05:09:26 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:26 INFO - '_': '/tools/buildbot/bin/python'} 05:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:26 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 05:09:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:09:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:09:28 INFO - Installing collected packages: mozsystemmonitor 05:09:28 INFO - Running setup.py install for mozsystemmonitor 05:09:28 INFO - Successfully installed mozsystemmonitor 05:09:28 INFO - Cleaning up... 05:09:28 INFO - Return code: 0 05:09:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:09:28 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')]} 05:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:28 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')]} 05:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:28 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 0x1e93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2154180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', '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 05:09:28 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 05:09:28 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 05:09:28 INFO - Using env: {'DISPLAY': ':0', 05:09:28 INFO - 'HOME': '/home/cltbld', 05:09:28 INFO - 'LANG': 'en_US.UTF-8', 05:09:28 INFO - 'LANGUAGE': 'en_US:en', 05:09:28 INFO - 'LOGNAME': 'cltbld', 05:09:28 INFO - 'MAIL': '/var/mail/cltbld', 05:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:28 INFO - 'MOZ_NO_REMOTE': '1', 05:09:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:28 INFO - 'NO_EM_RESTART': '1', 05:09:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:28 INFO - 'PWD': '/builds/slave/test', 05:09:28 INFO - 'SHELL': '/bin/bash', 05:09:28 INFO - 'SHLVL': '1', 05:09:28 INFO - 'TERM': 'linux', 05:09:28 INFO - 'TMOUT': '86400', 05:09:28 INFO - 'USER': 'cltbld', 05:09:28 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:28 INFO - '_': '/tools/buildbot/bin/python'} 05:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:28 INFO - Downloading/unpacking jsonschema==2.5.1 05:09:28 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 05:09:28 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 05:09:28 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 05:09:28 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 05:09:28 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 05:09:28 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 05:09:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:30 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:31 INFO - warning: no files found matching '*.txt' 05:09:31 INFO - no previously-included directories found matching 'build' 05:09:31 INFO - no previously-included directories found matching 'dist' 05:09:31 INFO - no previously-included directories found matching '.git*' 05:09:31 INFO - Installing collected packages: jsonschema, functools32 05:09:31 INFO - Running setup.py install for functools32 05:09:31 INFO - warning: no files found matching '*.txt' 05:09:31 INFO - no previously-included directories found matching 'build' 05:09:31 INFO - no previously-included directories found matching 'dist' 05:09:31 INFO - no previously-included directories found matching '.git*' 05:09:31 INFO - Successfully installed jsonschema functools32 05:09:31 INFO - Cleaning up... 05:09:31 INFO - Return code: 0 05:09:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:09:31 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')]} 05:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 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')]} 05:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 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 0x1e93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2154180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', '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 05:09:31 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 05:09:31 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 05:09:31 INFO - Using env: {'DISPLAY': ':0', 05:09:31 INFO - 'HOME': '/home/cltbld', 05:09:31 INFO - 'LANG': 'en_US.UTF-8', 05:09:31 INFO - 'LANGUAGE': 'en_US:en', 05:09:31 INFO - 'LOGNAME': 'cltbld', 05:09:31 INFO - 'MAIL': '/var/mail/cltbld', 05:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:31 INFO - 'MOZ_NO_REMOTE': '1', 05:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:31 INFO - 'NO_EM_RESTART': '1', 05:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:31 INFO - 'PWD': '/builds/slave/test', 05:09:31 INFO - 'SHELL': '/bin/bash', 05:09:31 INFO - 'SHLVL': '1', 05:09:31 INFO - 'TERM': 'linux', 05:09:31 INFO - 'TMOUT': '86400', 05:09:31 INFO - 'USER': 'cltbld', 05:09:31 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:31 INFO - '_': '/tools/buildbot/bin/python'} 05:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:09:31 INFO - Cleaning up... 05:09:31 INFO - Return code: 0 05:09:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09:31 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')]} 05:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 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')]} 05:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:31 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 0x1e93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2154180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', '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 05:09:31 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 05:09:31 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 05:09:31 INFO - Using env: {'DISPLAY': ':0', 05:09:31 INFO - 'HOME': '/home/cltbld', 05:09:31 INFO - 'LANG': 'en_US.UTF-8', 05:09:31 INFO - 'LANGUAGE': 'en_US:en', 05:09:31 INFO - 'LOGNAME': 'cltbld', 05:09:31 INFO - 'MAIL': '/var/mail/cltbld', 05:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:31 INFO - 'MOZ_NO_REMOTE': '1', 05:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:31 INFO - 'NO_EM_RESTART': '1', 05:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:31 INFO - 'PWD': '/builds/slave/test', 05:09:31 INFO - 'SHELL': '/bin/bash', 05:09:31 INFO - 'SHLVL': '1', 05:09:31 INFO - 'TERM': 'linux', 05:09:31 INFO - 'TMOUT': '86400', 05:09:31 INFO - 'USER': 'cltbld', 05:09:31 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:31 INFO - '_': '/tools/buildbot/bin/python'} 05:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:31 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:31 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 05:09:33 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:33 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 05:09:33 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 05:09:33 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 05:09:33 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 05:09:33 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 05:09:33 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 05:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:34 INFO - Downloading docopt-0.6.1.tar.gz 05:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:34 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:34 INFO - Running setup.py install for blobuploader 05:09:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:34 INFO - Running setup.py install for requests 05:09:34 INFO - Running setup.py install for docopt 05:09:34 INFO - Successfully installed blobuploader requests docopt 05:09:34 INFO - Cleaning up... 05:09:34 INFO - Return code: 0 05:09:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:34 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 0x1e93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2154180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', '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 05:09:34 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 05:09:34 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 05:09:34 INFO - Using env: {'DISPLAY': ':0', 05:09:34 INFO - 'HOME': '/home/cltbld', 05:09:34 INFO - 'LANG': 'en_US.UTF-8', 05:09:34 INFO - 'LANGUAGE': 'en_US:en', 05:09:34 INFO - 'LOGNAME': 'cltbld', 05:09:34 INFO - 'MAIL': '/var/mail/cltbld', 05:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:34 INFO - 'MOZ_NO_REMOTE': '1', 05:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:34 INFO - 'NO_EM_RESTART': '1', 05:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:34 INFO - 'PWD': '/builds/slave/test', 05:09:34 INFO - 'SHELL': '/bin/bash', 05:09:34 INFO - 'SHLVL': '1', 05:09:34 INFO - 'TERM': 'linux', 05:09:34 INFO - 'TMOUT': '86400', 05:09:34 INFO - 'USER': 'cltbld', 05:09:34 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:34 INFO - '_': '/tools/buildbot/bin/python'} 05:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:35 INFO - Running setup.py (path:/tmp/pip-TLMGnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:35 INFO - Running setup.py (path:/tmp/pip-hnTOAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:35 INFO - Running setup.py (path:/tmp/pip-6VF_d3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:35 INFO - Running setup.py (path:/tmp/pip-xirP5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:35 INFO - Running setup.py (path:/tmp/pip-jG8aPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:35 INFO - Running setup.py (path:/tmp/pip-bbHTNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:35 INFO - Running setup.py (path:/tmp/pip-W_gjf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:35 INFO - Running setup.py (path:/tmp/pip-dKz0bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:35 INFO - Running setup.py (path:/tmp/pip-c0mzPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:35 INFO - Running setup.py (path:/tmp/pip-KFM5et-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:35 INFO - Running setup.py (path:/tmp/pip-W2K57r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:35 INFO - Running setup.py (path:/tmp/pip-j7lglu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:36 INFO - Running setup.py (path:/tmp/pip-oDzBNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:36 INFO - Running setup.py (path:/tmp/pip-oScBD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:36 INFO - Running setup.py (path:/tmp/pip-3XX91B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:36 INFO - Running setup.py (path:/tmp/pip-E7ako0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:36 INFO - Running setup.py (path:/tmp/pip-lzWdy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:36 INFO - Running setup.py install for manifestparser 05:09:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:36 INFO - Running setup.py install for mozcrash 05:09:36 INFO - Running setup.py install for mozdebug 05:09:36 INFO - Running setup.py install for mozdevice 05:09:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:36 INFO - Running setup.py install for mozfile 05:09:36 INFO - Running setup.py install for mozhttpd 05:09:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Running setup.py install for mozinfo 05:09:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Running setup.py install for mozInstall 05:09:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Running setup.py install for mozleak 05:09:37 INFO - Running setup.py install for mozlog 05:09:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Running setup.py install for moznetwork 05:09:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Running setup.py install for mozprocess 05:09:37 INFO - Running setup.py install for mozprofile 05:09:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Running setup.py install for mozrunner 05:09:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:37 INFO - Running setup.py install for mozscreenshot 05:09:38 INFO - Running setup.py install for moztest 05:09:38 INFO - Running setup.py install for mozversion 05:09:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:38 INFO - Cleaning up... 05:09:38 INFO - Return code: 0 05:09:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:38 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 0x1e93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2154180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', '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 05:09:38 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 05:09:38 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 05:09:38 INFO - Using env: {'DISPLAY': ':0', 05:09:38 INFO - 'HOME': '/home/cltbld', 05:09:38 INFO - 'LANG': 'en_US.UTF-8', 05:09:38 INFO - 'LANGUAGE': 'en_US:en', 05:09:38 INFO - 'LOGNAME': 'cltbld', 05:09:38 INFO - 'MAIL': '/var/mail/cltbld', 05:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:38 INFO - 'MOZ_NO_REMOTE': '1', 05:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:38 INFO - 'NO_EM_RESTART': '1', 05:09:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:38 INFO - 'PWD': '/builds/slave/test', 05:09:38 INFO - 'SHELL': '/bin/bash', 05:09:38 INFO - 'SHLVL': '1', 05:09:38 INFO - 'TERM': 'linux', 05:09:38 INFO - 'TMOUT': '86400', 05:09:38 INFO - 'USER': 'cltbld', 05:09:38 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:38 INFO - '_': '/tools/buildbot/bin/python'} 05:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:38 INFO - Running setup.py (path:/tmp/pip-lut5_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:38 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)) 05:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:38 INFO - Running setup.py (path:/tmp/pip-RX6Cv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:38 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)) 05:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:38 INFO - Running setup.py (path:/tmp/pip-1ns0hI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:38 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)) 05:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:38 INFO - Running setup.py (path:/tmp/pip-1wH8Z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:38 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)) 05:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:38 INFO - Running setup.py (path:/tmp/pip-_5UDwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:38 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)) 05:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:38 INFO - Running setup.py (path:/tmp/pip-9pUnP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:38 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)) 05:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:38 INFO - Running setup.py (path:/tmp/pip-yOoZfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:38 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)) 05:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:38 INFO - Running setup.py (path:/tmp/pip-17XpKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:39 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)) 05:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:39 INFO - Running setup.py (path:/tmp/pip-vrpfPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:39 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)) 05:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:39 INFO - Running setup.py (path:/tmp/pip-HvzDPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:39 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)) 05:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:39 INFO - Running setup.py (path:/tmp/pip-Lp1hq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:39 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)) 05:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:39 INFO - Running setup.py (path:/tmp/pip-eS8B5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:39 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)) 05:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:39 INFO - Running setup.py (path:/tmp/pip-RNkoYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:39 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)) 05:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:39 INFO - Running setup.py (path:/tmp/pip-PfHlZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:39 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)) 05:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:39 INFO - Running setup.py (path:/tmp/pip-9ru8Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:39 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)) 05:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:39 INFO - Running setup.py (path:/tmp/pip-VJUHwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:39 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)) 05:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:39 INFO - Running setup.py (path:/tmp/pip-ye1Zl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 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)) 05:09:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:41 INFO - Downloading blessings-1.6.tar.gz 05:09:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:41 INFO - Installing collected packages: blessings 05:09:41 INFO - Running setup.py install for blessings 05:09:41 INFO - Successfully installed blessings 05:09:41 INFO - Cleaning up... 05:09:41 INFO - Return code: 0 05:09:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:42 INFO - Reading from file tmpfile_stdout 05:09:42 INFO - Current package versions: 05:09:42 INFO - argparse == 1.2.1 05:09:42 INFO - blessings == 1.6 05:09:42 INFO - blobuploader == 1.2.4 05:09:42 INFO - docopt == 0.6.1 05:09:42 INFO - functools32 == 3.2.3-2 05:09:42 INFO - jsonschema == 2.5.1 05:09:42 INFO - manifestparser == 1.1 05:09:42 INFO - mozInstall == 1.12 05:09:42 INFO - mozcrash == 0.17 05:09:42 INFO - mozdebug == 0.1 05:09:42 INFO - mozdevice == 0.48 05:09:42 INFO - mozfile == 1.2 05:09:42 INFO - mozhttpd == 0.7 05:09:42 INFO - mozinfo == 0.9 05:09:42 INFO - mozleak == 0.1 05:09:42 INFO - mozlog == 3.3 05:09:42 INFO - moznetwork == 0.27 05:09:42 INFO - mozprocess == 0.23 05:09:42 INFO - mozprofile == 0.28 05:09:42 INFO - mozrunner == 6.12 05:09:42 INFO - mozscreenshot == 0.1 05:09:42 INFO - mozsystemmonitor == 0.3 05:09:42 INFO - moztest == 0.7 05:09:42 INFO - mozversion == 1.4 05:09:42 INFO - psutil == 3.1.1 05:09:42 INFO - requests == 1.2.3 05:09:42 INFO - wsgiref == 0.1.2 05:09:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:42 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 0x1e93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2154180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', '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 05:09:42 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 05:09:42 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 05:09:42 INFO - Using env: {'DISPLAY': ':0', 05:09:42 INFO - 'HOME': '/home/cltbld', 05:09:42 INFO - 'LANG': 'en_US.UTF-8', 05:09:42 INFO - 'LANGUAGE': 'en_US:en', 05:09:42 INFO - 'LOGNAME': 'cltbld', 05:09:42 INFO - 'MAIL': '/var/mail/cltbld', 05:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:42 INFO - 'MOZ_NO_REMOTE': '1', 05:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:42 INFO - 'NO_EM_RESTART': '1', 05:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:42 INFO - 'PWD': '/builds/slave/test', 05:09:42 INFO - 'SHELL': '/bin/bash', 05:09:42 INFO - 'SHLVL': '1', 05:09:42 INFO - 'TERM': 'linux', 05:09:42 INFO - 'TMOUT': '86400', 05:09:42 INFO - 'USER': 'cltbld', 05:09:42 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:42 INFO - '_': '/tools/buildbot/bin/python'} 05:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:42 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)) 05:09:42 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)) 05:09:42 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)) 05:09:42 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)) 05:09:42 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)) 05:09:42 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)) 05:09:42 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)) 05:09:42 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)) 05:09:42 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)) 05:09:42 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)) 05:09:42 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)) 05:09:42 INFO - Cleaning up... 05:09:42 INFO - Return code: 0 05:09:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e93030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2154180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, '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': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', '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 05:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:09:42 INFO - Using env: {'DISPLAY': ':0', 05:09:42 INFO - 'HOME': '/home/cltbld', 05:09:42 INFO - 'LANG': 'en_US.UTF-8', 05:09:42 INFO - 'LANGUAGE': 'en_US:en', 05:09:42 INFO - 'LOGNAME': 'cltbld', 05:09:42 INFO - 'MAIL': '/var/mail/cltbld', 05:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:42 INFO - 'MOZ_NO_REMOTE': '1', 05:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:42 INFO - 'NO_EM_RESTART': '1', 05:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:42 INFO - 'PWD': '/builds/slave/test', 05:09:42 INFO - 'SHELL': '/bin/bash', 05:09:42 INFO - 'SHLVL': '1', 05:09:42 INFO - 'TERM': 'linux', 05:09:42 INFO - 'TMOUT': '86400', 05:09:42 INFO - 'USER': 'cltbld', 05:09:42 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:42 INFO - '_': '/tools/buildbot/bin/python'} 05:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:09:42 INFO - Cleaning up... 05:09:42 INFO - Return code: 0 05:09:42 INFO - Running post-action listener: _resource_record_post_action 05:09:42 INFO - Running post-action listener: _start_resource_monitoring 05:09:42 INFO - Starting resource monitoring. 05:09:42 INFO - [mozharness: 2016-08-01 12:09:42.540425Z] Finished create-virtualenv step (success) 05:09:42 INFO - [mozharness: 2016-08-01 12:09:42.540863Z] Running install step. 05:09:42 INFO - Running pre-action listener: _resource_record_pre_action 05:09:42 INFO - Running main action method: install 05:09:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:42 INFO - Reading from file tmpfile_stdout 05:09:42 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:42 INFO - Reading from file tmpfile_stdout 05:09:42 INFO - Output received: 05:09:42 INFO - Usage: mozinstall [options] installer 05:09:42 INFO - Options: 05:09:42 INFO - -h, --help show this help message and exit 05:09:42 INFO - -d DEST, --destination=DEST 05:09:42 INFO - Directory to install application into. [default: 05:09:42 INFO - "/builds/slave/test"] 05:09:42 INFO - --app=APP Application being installed. [default: firefox] 05:09:42 INFO - mkdir: /builds/slave/test/build/application 05:09:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:09:59 INFO - Reading from file tmpfile_stdout 05:09:59 INFO - Output received: 05:09:59 INFO - /builds/slave/test/build/application/firefox/firefox 05:09:59 INFO - Running post-action listener: _resource_record_post_action 05:09:59 INFO - [mozharness: 2016-08-01 12:09:59.723731Z] Finished install step (success) 05:09:59 INFO - [mozharness: 2016-08-01 12:09:59.724009Z] Running run-tests step. 05:09:59 INFO - Running pre-action listener: _resource_record_pre_action 05:09:59 INFO - Running main action method: run_tests 05:09:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:59 INFO - Python 2.7.3 05:09:59 INFO - Return code: 0 05:09:59 INFO - grabbing minidump binary from tooltool 05:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:59 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 0x21523b0>, '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 0x21532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:59 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 05:09:59 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 05:09:59 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 05:09:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:59 INFO - Return code: 0 05:09:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:09:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:09:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 05:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 05:09:59 INFO - Using env: {'DISPLAY': ':0', 05:09:59 INFO - 'HOME': '/home/cltbld', 05:09:59 INFO - 'LANG': 'en_US.UTF-8', 05:09:59 INFO - 'LANGUAGE': 'en_US:en', 05:09:59 INFO - 'LOGNAME': 'cltbld', 05:09:59 INFO - 'MAIL': '/var/mail/cltbld', 05:09:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:09:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:59 INFO - 'MOZ_NO_REMOTE': '1', 05:09:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:09:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:59 INFO - 'NO_EM_RESTART': '1', 05:09:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:59 INFO - 'PWD': '/builds/slave/test', 05:09:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:09:59 INFO - 'SHELL': '/bin/bash', 05:09:59 INFO - 'SHLVL': '1', 05:09:59 INFO - 'TERM': 'linux', 05:09:59 INFO - 'TMOUT': '86400', 05:09:59 INFO - 'USER': 'cltbld', 05:09:59 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143', 05:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:59 INFO - '_': '/tools/buildbot/bin/python'} 05:09:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 05:10:00 INFO - mozversion application_buildid: 20160801023002 05:10:00 INFO - mozversion application_changeset: c94403616cdd5e67861c73b87e7eaec63939c375 05:10:00 INFO - mozversion application_display_name: Nightly 05:10:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:10:00 INFO - mozversion application_name: Firefox 05:10:00 INFO - mozversion application_remotingname: firefox 05:10:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:10:00 INFO - mozversion application_vendor: Mozilla 05:10:00 INFO - mozversion application_version: 50.0a1 05:10:00 INFO - mozversion platform_buildid: 20160801023002 05:10:00 INFO - mozversion platform_changeset: c94403616cdd5e67861c73b87e7eaec63939c375 05:10:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:10:00 INFO - mozversion platform_version: 50.0a1 05:10:00 INFO - using testdate: 1470053400 05:10:00 INFO - actual date: 1470053400 05:10:00 INFO - starting webserver on 'localhost:34035' 05:10:00 INFO - SUITE-START | Running 2 tests 05:10:00 INFO - TEST-START | dromaeo_css 05:10:00 INFO - operating with platform_type : linux_ 05:10:00 INFO - Initialising browser for dromaeo_css test... 05:10:00 INFO - TEST-INFO | started process 6229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHtxrSF/profile http://localhost:34035/getInfo.html) 05:10:03 INFO - PROCESS | 6229 | __metrics Screen width/height:1600/1200 05:10:03 INFO - PROCESS | 6229 | colorDepth:24 05:10:03 INFO - PROCESS | 6229 | Browser inner width/height: 1024/697 05:10:03 INFO - PROCESS | 6229 | __metrics 05:10:04 INFO - PROCESS | 6229 | 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" 05:10:04 INFO - PROCESS | 6229 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:10:05 INFO - PROCESS | 6229 | console.error: PushService: 05:10:05 INFO - PROCESS | 6229 | stateChangeProcessEnqueue: Error transitioning state 05:10:05 INFO - PROCESS | 6229 | UnknownError 05:10:05 INFO - TEST-INFO | 6229: exit 0 05:10:05 INFO - Browser initialized. 05:10:05 INFO - Running cycle 1/1 for dromaeo_css test... 05:10:05 INFO - TEST-INFO | started process 6309 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHtxrSF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:17 INFO - PROCESS | 6309 | RSS: Main: 155467776 05:10:17 INFO - PROCESS | 6309 | 05:11:08 INFO - PROCESS | 6309 | 1470053468261 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] 05:13:17 INFO - PROCESS | 6309 | Cycle 1(1): loaded http://localhost:34035/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34035/tests/dromaeo/cssquery-ext.html) 05:13:18 INFO - PROCESS | 6309 | RSS: Main: 264327168 05:13:18 INFO - PROCESS | 6309 | 05:16:20 INFO - PROCESS | 6309 | Cycle 1(1): loaded http://localhost:34035/tests/dromaeo/cssquery-ext.html (next: http://localhost:34035/tests/dromaeo/cssquery-jquery.html) 05:16:21 INFO - PROCESS | 6309 | RSS: Main: 242855936 05:16:21 INFO - PROCESS | 6309 | 05:19:23 INFO - PROCESS | 6309 | Cycle 1(1): loaded http://localhost:34035/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34035/tests/dromaeo/cssquery-mootools.html) 05:19:24 INFO - PROCESS | 6309 | RSS: Main: 185282560 05:19:24 INFO - PROCESS | 6309 | 05:22:26 INFO - PROCESS | 6309 | Cycle 1(1): loaded http://localhost:34035/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34035/tests/dromaeo/cssquery-prototype.html) 05:22:26 INFO - PROCESS | 6309 | RSS: Main: 238694400 05:22:26 INFO - PROCESS | 6309 | 05:25:29 INFO - PROCESS | 6309 | Cycle 1(1): loaded http://localhost:34035/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34035/tests/dromaeo/cssquery-yui.html) 05:25:29 INFO - PROCESS | 6309 | RSS: Main: 185638912 05:25:29 INFO - PROCESS | 6309 | 05:28:32 INFO - PROCESS | 6309 | Cycle 1(1): loaded http://localhost:34035/tests/dromaeo/cssquery-yui.html (next: http://localhost:34035/tests/dromaeo/cssquery-dojo.html) 05:28:32 INFO - PROCESS | 6309 | RSS: Main: 274309120 05:28:32 INFO - PROCESS | 6309 | 05:28:32 INFO - PROCESS | 6309 | __start_tp_report 05:28:32 INFO - PROCESS | 6309 | _x_x_mozilla_page_load 05:28:32 INFO - PROCESS | 6309 | _x_x_mozilla_page_load_details 05:28:32 INFO - PROCESS | 6309 | |i|pagename|runs| 05:28:32 INFO - PROCESS | 6309 | |0;dojo.html;3011.457937571237;3053.709897559731;3078.7690923180753;3084.2906131589734;3092.5825013623153;5242.397124330703;5292.682439053659;5301.363836339639;5375.220593014015;5402.567794576439;5340.225667278242;5348.812791552296;5355.08963476209;5410.864728381793;5419.403865574784;4868.902621947559;4890.510948905107;4912.803487860499;4920.483349248331;5032.471590483002;4946;4981.028699403618;5000.274960130779;5009.198528235483;5013.197888337867;4791.257355109981;4804.927926081112;4842.854714358539;4900.313956046157;4971.776270067838;4791.760411979387;4802.207635740098;4838.153323168432;4892.168331383673;4938.4814594467425;4864.36763220779;4904.803807847682;4908.705477671351;4914.631402644816;4922.163232250526;5208.401033881077;5259.605529585297;5348;5411.350637923435;5431.995080910045;5717.542596592262;5766.106253530709;5768.077107662753;5776.855578610526;5777.855553611152;5761.884762304772;5818.505427038707;5820.767169313222;5826.126081087828;5828.067509198508;5242.79028838846;5360.249565060894;5388.649737767111;5396.055690254189;5417.566594672417;5954.46409823118;6002.729877155539;6003.159557661931;6007.429294217042;6010.038393856962;5752.309722833287;5759.884802303931;5762.567807414461;5763.827085187451;5766.711664416763;5562.193481945176;5674.148877668383;5696.51579615729;5719.513841323451;5824.854378640483;5725.799597014085;5728.828135155952;5778.739956701958;5786.305643322744;5789.218455508537;5702.781298933728;5710.086386178192;5736;5747.3677895431465;5751.626144300607;4304.677149213821;4346.261135606955;4429.623482004002;4434.401355816924;4481.775911204427;3876.321643712339;3911.2764138633875;3918.040080180374;3931.095847954929;3955.129871428281;5356.517913387814;5381.9461805382225;5388.380336261302;5388.757505912165;5391.649542779707;5531.3915469298345;5655.179998900218;5758.3665796762325;5774.451427114502;5817.127430885316;5374.892502149935;5382.703951282717;5399.568034557226;5423.566114710814;5428.158635411518;4978.5768209701855;4979.3526841510375;4981.501849815062;4982.078315511642;5022.522860328263;5782.421757824268;5859.267591551142;5931.822045338647;5934.347221805598;5945.286565612154;5738.340090889435;5768.480836724715;5775.595708300379;5853.882922341614;5855.063189889597;5913.05391137416;5948.375420580776;5966;5973.671448070398;5976;5469.480399362054;5607;5611.158326250932;5659.207710920557;5671.17767923657;5726.312842458765;5810.418958104325;5820.30156381237;5841.620294680833;5851.590388672752;5651.689157096234;5694.544436445157;5695.288088988877;5697.487226149667;5710.65736055838;5376.623636345575;5532.751026203911;5686.9715651421475;5688.914666280092;5691.373948865705;5484.478974497416;5534.557235421237;5544.279243698294;5546.58400619947;5602.411746766531;5582.134769110794;5630.5214056805635;5695.430456954271;5706.771729130788;5710.114932185518;5403.486668766461;5418.512333889968;5501.5323697486165;5510.559155267489;5512.952539017574 05:28:32 INFO - PROCESS | 6309 | |1;ext.html;599.3467120838285;615.258613370888;632.5256057956531;635.1108448172558;651.5960104735059;33446.66553334466;44916;46039.76980115095;46149.538504615026;46207.76896115515;46814;47315.053698926;47391;47399.28901066487;47421.525784742145;9040.050794666548;9163.862542061874;9175.770605734877;9184.311176661744;9200.125988031126;8937.240334571537;9011.54942252888;9034.728958131267;9035.864462033074;9044.728658140233;9032.909670903306;9076.727698169065;9083.863742043875;9086.091390860895;9086.954565227164;9045.547722613843;9067.138621830916;9087.136722011399;9093.04523025086;9095.590698418553;9052.954735226282;9116.726498205064;9125.041870603558;9127.361084724072;9136.177744003071;44517;44713.776431117636;44805.103897922185;44892.3266151008;44960;3540.044188069207;3820.102275965146;4072.6131017553284;4278.2085314216965;4406.8457603984025;33793.6620633793;34213.82893085544;34489;35226.47160292598;36046;4376.452943382077;4408.175488507892;4454;4579.610733087691;4604.585587297126;250193.7490312555;250651;251056;251177;251394;61855.38144618496;61914;62107;62394.376056238856;62562.68718656377;65471.67264163744;65586.34413655802;65660;65664.34335656678;65760.67119664371;12995.28525931064;16995.150242487827;17126.828731712772;17279.82720172782;17437.65124697499;12666.683332916751;12684.42920068599;12717.300548469922;12729.236245825095;12754.61736147917;18738.812611873705;18857;18874;18921.337753178574;18958.431247062334;5800.651960882361;6587.670616469158;6724.562903411263;6883.346082122191;6901.309869013058;987.6049580167846;1022.9488525573692;1045.8849526552074;1098.8571485706807;1168.415792103948;33068.66931330656;33106.3378732424;33127.83436082852;33156.3368732624;33855.830720846236;1101.8485682461846;1143.7255058785997;1165.3648761425043;1201.3152503073165;1236.7402845402387;17326.574279600867;17481.82518174802;17482.688798340176;17536.561585960197;17642.911785441247;472296;473142;473444;475084;475095;59940;60334.094988575205;60415.093773593435;60454;60502.69748651228;1217.7807994561065;1335.5726167626267;1362.4413990264143;1367.1045465220298;1381.709840933395;31709.682903171597;34963;35360.64639353574;35486.645133548336;35671.46492802558;55513.444865550824;56481.71759141178;56513.434865650815;56564;56639;470947;479397;482128;482581;483814;43967.340489893315;45559.31661025021;46146;48535.5146448531;50669.23996140135;50777;50806.74596626993;51406;52094.739526302124;52240.73879630578;42525.149497010505;42690;43664.56335436732;44158.55841441544;45336;42959.31539416226;43711;43974.78012609916;45027.77486112549;45374.546254538356;43038.78480607577;43910.78044609884;44182;44801.327980080976;45030.324545132506;37399;39043.41434878536;39258;40309.798451007555;40680.59319406768;29047;29579.26136222579;29757.256068598457;29919.401611968075;29938 05:28:32 INFO - PROCESS | 6309 | |2;jquery.html;368.3847973883614;370.64973599948024;375.32441605110796;376.95288088988866;395.2411370169275;14794.112353258799;18633.254669813206;19430.805691943075;20412.081456334465;20414.69377959332;16230.594235144128;19109.426717198505;19562.31531896384;19655.508612284666;19720.30978915738;5516.641418307807;5763.135529670562;5777.45114214149;5778.27771528559;5778.5377169826525;5444.537214336786;5619.129034999582;5619.325680918296;5652.180433837091;5656.038473459501;5610.635308704942;5611.80358687446;5611.831645050655;5617.0731829248125;5617.466340697648;5444.373897001856;5571.9442805572035;5577.05190117681;5582.330120385539;5609.3268807743125;4903.975480122613;4909.754512274372;4913.14020046494;4919.458859525449;4945.332380128672;23982.52034959291;24029;24087.156949506858;24099;24261.029558817627;2462.704475462956;2626.8423894566195;3056.2359410147465;3062.4028314478687;3149.811011339327;13883.37524811386;14177.78733319001;14572.70854582913;14651.926740366336;14899.031562948374;1887.3205645967441;1889.896055716935;1905.3331334033116;1914.8946807925558;1918.424472658197;50169.498305017216;78103.21896781072;78898.60550697267;79748.60125699392;80909.59545202296;9659.082387173208;9682.70951871445;9713.660021899199;9844.310898237198;9969.451680157661;13868.653283667787;13872.653183670489;13881.4447422104;13947.790783138262;13981.580552583438;7827.178146294671;7926.643301051467;7963.044434667877;8018.5990700464745;8098.433109682265;7640.426967977424;7771.572563508948;7917.643706033243;7943.721969731032;7957.681692732226;6399.176234773067;6402.583832050902;6403.743850245938;6434.7104380303;6448.613083215022;1768.4341010876653;1780.1900135438345;1797.5845892803516;1800.1359347513267;1819.6691873341626;962.6005207838808;1033.5762337441613;1079.0019232210175;1080.303204433152;1134.478140055567;20294.492637684176;20689.58620827605;20712.48218794542;20868.791312086683;20902.58194836095;1016.1718199667248;1023.1661196125134;1030.6341248856697;1036.7563622548696;1037.1702637889657;9984.500774961369;10438.947805260925;10842.186835987333;11022.889771102347;11529.365884876244;524513;532326;541448;553469;558329;86529.56735216283;89074.55462722645;91600;91798.54100729454;93630;1020.8009438159636;1044.5456226541478;1045.1795340657548;1057.8095942730383;1065.4512925843196;16891.408800691916;17466.061539076774;17830.286788528312;18107;18257.634847303245;31760;42253;42378.78810605927;42577.148457031304;43507.564924351616;58362.4163758374;58466;59690;59956;60303.49241269004;7648.73229436967;7875.921240787518;7956.562389068657;7995.600219989094;8187.672493100209;12065.758684826078;12602.87397126017;13035.348232588522;13316.202312687663;13459.192448453125;11350.772984540428;11453.427328633701;11621.593244236412;11736.823947640622;11865.822012669643;11009.339439633648;11205.215634905479;11340.262882912584;11547.942260288644;11639.6508104757;11443.313401195956;11606.187566870238;11689.590864319707;11772.823407649063;12130.272183669009;7719.922800772145;7831.099423566135;8016.318612917968;8048.5975701215875;8058.597070146587;7936.127026027017;10608.522616482123;11263.549458021915;11378;11881.21781732747 05:28:32 INFO - PROCESS | 6309 | |3;mootools.html;1361.5098564516766;1472.7128209999055;1522.528016314942;1674.9413770518033;1823.9785719996803;7909;8324.458910170843;8340.499570025802;8523.275521580657;8540.060593334743;8335.958320208405;8471;8472.110428405022;8475.32197424206;8482.66069357225;2281.5665023645456;2307.077169132344;2309.2379514760087;2310.8266879983985;2313.5141620259683;2261.2424837679396;2333.556624241397;2336;2339.6256598944174;2343.168175297762;2296.7932886040253;2310.295359915234;2323.3146221864504;2326.9418264543438;2331.7901388875;2244.86530808152;2266.580682573729;2269.568781931436;2270.5685919675207;2277.498950230955;2249.6962910007105;2250.606143924823;2251.324602619208;2256.605094108541;2265.161890100673;8452.450590711645;8465.53439560824;8507.574621268912;8512.44669096513;8514.829703405898;4947.332110165154;5140.563052140573;5144.588432925357;5145.3568303962;5155.587552995751;3247.561579186803;3382.2897191589796;3442.277121804424;3462.8614855405754;3477.426224672926;1135.409587014756;1138.402338772143;1199.2205066706624;1206.1315852586124;1208.9032877369789;11567.13246506507;11569.305841649528;11657.242279251821;11660.125490588154;11662.88337116635;4091.3044782387065;4278.614924656796;4354.455693038371;4361.171377438277;4487.640988720895;9238.491882946368;9243.352965292364;9244.214241789523;9248.722538323862;9249.768755781159;5129.666571672829;5131.435542090367;5145;5149.382074151126;5151.330327057459;4312.547182545787;4673.065386922629;4782.306565547976;4834.347363106006;4850.054239423279;3710.740248182601;3722.9069273268383;3831.2529056834205;3841.615838416136;3849.5957924417535;1134.1493879590307;1145.8682251541013;1147.2944139354238;1148.9827652585218;1152.9250598711058;794.6662401791261;925.5002298758595;927.6567669962158;928.7817362919709;973.7127547373542;5438.755256013489;5542.11326187808;5828.854278643068;5838.766449342063;5898.292204935349;925.5881132895797;970.8009857979098;983.7884854756262;998.6604554451376;1043.7599352149048;3781.8865434037025;3871;4003.939940900889;4056.553779084298;4083.89790255246;10149.238807089498;10499.632512862014;10507.422091785025;10519.789604207874;10520.421376824195;10443.060124588823;10457;10474.214433917487;10493.580256789795;10541.314814537183;7741.690332386642;7859.842803143905;7864.921350786533;7874.01574803161;7881.527108373516;5464.398916119303;5538.196961440649;5625.353084395183;5625.353084395347;5701.116326969326;9568.66509672186;9713.951430242803;9765.80468390614;9771.31600787938;9917.15704165154;9776.03857307585;9800.65697700577;9982.251331150337;10139.64511242103;10151.847722284025;6611.239707433514;6825.146856642919;6917.965410172917;7096.7516136934955;7212.062431883822;8107.675692972215;8462.957685211535;8479.61841717112;8479.745607631781;8490.23587877094;7289.161746399232;7335.926640733738;7393.482456228013;7440.665170067252;7608.543487390774;7301.853962920818;7328.193898671036;7412.4070074394995;7501.062367204099;7711.190325016017;7021.262767409348;7199.604021778852;7201.675924583302;7216.170140433917;7224.169220539705;4903.41159060915;4955.8017679292425;5011.123053465702;5069.493050695049;5161.483851614808;4818.518148185153;4907.901841963212;4986.700797952134;5049.495050495068;5122.154844450624 05:28:32 INFO - PROCESS | 6309 | |4;prototype.html;300.87964814074365;315.6259832098963;319.69628852590046;321.50167240776807;322.31347230399246;4946.876328091799;5190.558802501787;5234.5027222413855;5275.129603615399;5291.206319052144;5197.870053248671;5214.523101526773;5216.973915130419;5231.738413079341;5232.816851410202;4071.2671719090554;4075.8981025474454;4079.714419990602;4082.387641853716;4084.24441478326;3961.1681546875193;4048.4939382577177;4061.5532291447917;4069.532003819554;4069.877903662895;4006.8397264109544;4039.3738970459626;4040.5353384360883;4047.1989964465824;4049.311617025113;4048.9190216195807;4051.574584668597;4052.959470405288;4054.965983674174;4063.512378514597;3553.751237413382;3586.1214002569277;3586.8206589670413;3604.4953706481115;3624.9275014499563;4889.144399730033;5095.363079615048;5124.795008199668;5183.844484665466;5196.53231209193;4598.149342371672;4600.769961501945;4608.101420223058;4609.262517997105;4636;5407.242985981966;5411.215373770781;5437.700926449043;5461.563074953994;5494.5054945055135;3665.6151104133946;3826.827792749333;3888.0862997195636;3902.7268091233627;3909.7263191576603;5380.838574842761;5539.335279766454;5620.241267428885;5626.971865140689;5629.57778166635;3833.9041523961564;3874.4575759393706;3882.165334453106;3882.941755873664;3997.2405243003736;4016.477857878457;4016.8995775105855;4023.8189281482405;4034.919301613951;4066.2883995301295;4396.142752163298;4544.613707834805;4627.236505976476;4650.348951146842;4658.650601204923;5414.458554144554;5421.186821976735;5426.511613954684;5427.348718153768;5452.509274165344;1334.7463981843418;1353.78339465685;1364.7338768939962;1367.596559015093;1370.8080868678394;829.1708291708292;863.1196179896596;867.4925168776336;892.5314210039782;896.5965315608003;4687.6249900007915;4694.85444752017;4702.365180700562;4702.459217190067;4720.409948756405;4916.582090522345;4941.75291235444;4954;4968.27959945813;4973.403191617029;4456.3538286948215;4768.976155119202;4773.904521909542;4779.593734532534;4804.77781273252;2594.299539124426;2628.4217472156097;2633.8024648151463;2643.299525625711;2645.3254420122744;5069.8592816615965;5072.974635126875;5085.211792172146;5132.230165475134;5162.3030890830005;4823.877493902672;4993.601791498386;5042.470540593258;5047.899042019212;5071.5689166420525;4988.625853061035;5021.870079232143;5046.520580544842;5075.568576671024;5092.821751238689;4872.904651000104;4942.4069111705385;4942.654014218971;4961.280614310833;4983.775730092227;4587.013792034729;4854.441739200037;4889.755512224303;4902.264660300983;4921.744955036443;5531.944680553143;5654.6889921054735;5773;5786.189933409242;5802.187693722966;3979.567355751869;3979.6617287530885;3986.5216174059296;4024.879253622396;4025.6175663311938;5369.597280203922;5584.581156413366;5588.189712491585;5686.061799802987;5704.857378565569;5490.945090549043;5503.229547863222;5507;5511.751971161227;5525.336959564878;5465.508104270635;5494.1758736189895;5494.80768173128;5537.640053396516;5538.529225015919;5182.455842136521;5341.305630268041;5412.78348866041;5467;5467.890642187054;5479.561555140349;5496.642718223303;5521.917171242354;5530.225768392509;5538.141588053858;5404.216388623706;5405.540529055074;5417.674939503642;5418.972905135449;5465.289512363367 05:28:32 INFO - PROCESS | 6309 | |5;yui.html;803.0965164190286;811.6144831205178;817.513579420245;824.3405275779375;829.6440896573358;15528;15875;16355.264013119408;16388.016718996852;16642.417515386933;16419.58951026222;16447.2598733057;16565.337386504554;16579.25393357299;16656.916715416406;804.8309854930471;826.859433896236;828.362161135925;829.4069740135802;829.5561874397199;664.6942406492994;787.2127872127872;817.15832692327;818.7298191596789;820.2207902492626;863.2489733931457;870.6952566601702;871.0766587417357;871.2289623683023;871.2463718883153;864.5590748718137;865.7878819689184;866.5017614871412;867.1285358215017;867.2281669314284;508.8015673887187;605.4883623338272;623.0560700538688;632.8133200705803;632.89873620221;14239;14266.429342826275;14288.285585720672;14289.142651440947;14291.499797507038;13807;14344.211068391234;14559.563213103624;14699.853001470063;14701.852981470262;12800;12806.61580152597;12869.944056636412;13238.933805331008;13541.729165416735;4149.730267532631;4212.157568486315;4216.683748718859;4233.51314598822;4244.830206791725;81743.59128204321;82173.58913205455;84108.15891841064;84438;84554;11530.077593792477;11538.538458461735;11589.304641721523;11672.474738636782;11942.641720748392;21552.461188470414;21569.460763481038;21617.459563510725;21638;21656.675149872764;8321.417500774887;8330.583470826434;8418;8504.149585041447;8754.47473151613;4606.861794146181;4762.261849413347;4795;4811.254255590389;4834.661573689878;3458.2219000724635;3551.94672079919;3556.982215088908;3560.2701446203464;3863.942040869389;688.4458011300851;689.427774946793;692.1486571517062;699.1784653032666;706.20199174932;664.5115839857797;707.9716811327593;728.5956294256632;736.5028605691137;744.985100297991;9486.288528360401;9853.24113228209;9855.655052073143;10159.136473399843;10483.318584291997;569.5073761196618;659.6174218952996;736.2674139231499;756.2664215710752;756.9857482303158;4605.263157894721;5053.5957123430035;5067.569256613156;5075.695458272442;5087.109755792795;120855;121562;126648;127475;132327;106538.46730766297;107348.46325768478;108621;108807;112776;61873;97349.51325243329;97563.51218244006;99542.00457995472;102057;9428.764280893032;9504.857427138737;9745.707628771148;9825.656102036395;9911;56829.431705682415;57034.43724502113;57209;57849;58157;76013.25415182875;82011.79882011752;82457.58771206345;84670;84873.15126848653;11796.056315494912;11828.881711182778;11981.161318707607;12052.457639406422;12437.378131093228;13192.868071319548;13432;13519.662008449868;14497.92751036238;14651.340689669203;13680.863191367958;13820.585382438543;14147.717045658823;14218.644533886736;14638.121712697272;14246.786298205743;14501.637459063608;14594.416223350947;15663.843361566238;16190.109543975195;13724.65688357759;14488.637784055483;15019.399224030916;15449.5365139046;15638.139902305482;11530.711732206762;11723.589674361356;11820.468078936297;12096.334701591144;12101.69745756363;9995.250356223167;11110.388928608681;11573.7106572333;11910.940445298065;12126.818097728717 05:28:32 INFO - PROCESS | 6309 | __end_tp_report 05:28:32 INFO - PROCESS | 6309 | __start_cc_report 05:28:32 INFO - PROCESS | 6309 | _x_x_mozilla_cycle_collect,991 05:28:32 INFO - PROCESS | 6309 | __end_cc_report 05:28:32 INFO - PROCESS | 6309 | __startTimestamp1470054512981__endTimestamp 05:28:32 INFO - PROCESS | 6309 | 05:28:32 INFO - PROCESS | 6309 | ------- Summary: start ------- 05:28:32 INFO - PROCESS | 6309 | Number of tests: 6 05:28:32 INFO - PROCESS | 6309 | 05:28:32 INFO - PROCESS | 6309 | [#0] dojo.html Cycles:165 Average:5343.04 Median:5512.95 stddev:614.58 (11.1%) stddev-sans-first:588.62 05:28:32 INFO - PROCESS | 6309 | Values: 3011.5 3053.7 3078.8 3084.3 3092.6 5242.4 5292.7 5301.4 5375.2 5402.6 5340.2 5348.8 5355.1 5410.9 5419.4 4868.9 4890.5 4912.8 4920.5 5032.5 4946.0 4981.0 5000.3 5009.2 5013.2 4791.3 4804.9 4842.9 4900.3 4971.8 4791.8 4802.2 4838.2 4892.2 4938.5 4864.4 4904.8 4908.7 4914.6 4922.2 5208.4 5259.6 5348.0 5411.4 5432.0 5717.5 5766.1 5768.1 5776.9 5777.9 5761.9 5818.5 5820.8 5826.1 5828.1 5242.8 5360.2 5388.6 5396.1 5417.6 5954.5 6002.7 6003.2 6007.4 6010.0 5752.3 5759.9 5762.6 5763.8 5766.7 5562.2 5674.1 5696.5 5719.5 5824.9 5725.8 5728.8 5778.7 5786.3 5789.2 5702.8 5710.1 5736.0 5747.4 5751.6 4304.7 4346.3 4429.6 4434.4 4481.8 3876.3 3911.3 3918.0 3931.1 3955.1 5356.5 5381.9 5388.4 5388.8 5391.6 5531.4 5655.2 5758.4 5774.5 5817.1 5374.9 5382.7 5399.6 5423.6 5428.2 4978.6 4979.4 4981.5 4982.1 5022.5 5782.4 5859.3 5931.8 5934.3 5945.3 5738.3 5768.5 5775.6 5853.9 5855.1 5913.1 5948.4 5966.0 5973.7 5976.0 5469.5 5607.0 5611.2 5659.2 5671.2 5726.3 5810.4 5820.3 5841.6 5851.6 5651.7 5694.5 5695.3 5697.5 5710.7 5376.6 5532.8 5687.0 5688.9 5691.4 5484.5 5534.6 5544.3 5546.6 5602.4 5582.1 5630.5 5695.4 5706.8 5710.1 5403.5 5418.5 5501.5 5510.6 5513.0 05:28:32 INFO - PROCESS | 6309 | 05:28:32 INFO - PROCESS | 6309 | [#1] ext.html Cycles:180 Average:58795.97 Median:44993.89 stddev:109919.43 (244.3%) stddev-sans-first:110140.93 05:28:32 INFO - PROCESS | 6309 | Values: 599.3 615.3 632.5 635.1 651.6 33446.7 44916.0 46039.8 46149.5 46207.8 46814.0 47315.1 47391.0 47399.3 47421.5 9040.1 9163.9 9175.8 9184.3 9200.1 8937.2 9011.5 9034.7 9035.9 9044.7 9032.9 9076.7 9083.9 9086.1 9087.0 9045.5 9067.1 9087.1 9093.0 9095.6 9053.0 9116.7 9125.0 9127.4 9136.2 44517.0 44713.8 44805.1 44892.3 44960.0 3540.0 3820.1 4072.6 4278.2 4406.8 33793.7 34213.8 34489.0 35226.5 36046.0 4376.5 4408.2 4454.0 4579.6 4604.6 250193.7 250651.0 251056.0 251177.0 251394.0 61855.4 61914.0 62107.0 62394.4 62562.7 65471.7 65586.3 65660.0 65664.3 65760.7 12995.3 16995.2 17126.8 17279.8 17437.7 12666.7 12684.4 12717.3 12729.2 12754.6 18738.8 18857.0 18874.0 18921.3 18958.4 5800.7 6587.7 6724.6 6883.3 6901.3 987.6 1022.9 1045.9 1098.9 1168.4 33068.7 33106.3 33127.8 33156.3 33855.8 1101.8 1143.7 1165.4 1201.3 1236.7 17326.6 17481.8 17482.7 17536.6 17642.9 472296.0 473142.0 473444.0 475084.0 475095.0 59940.0 60334.1 60415.1 60454.0 60502.7 1217.8 1335.6 1362.4 1367.1 1381.7 31709.7 34963.0 35360.6 35486.6 35671.5 55513.4 56481.7 56513.4 56564.0 56639.0 470947.0 479397.0 482128.0 482581.0 483814.0 43967.3 45559.3 46146.0 48535.5 50669.2 50777.0 50806.7 51406.0 52094.7 52240.7 42525.1 42690.0 43664.6 44158.6 45336.0 42959.3 43711.0 43974.8 45027.8 45374.5 43038.8 43910.8 44182.0 44801.3 45030.3 37399.0 39043.4 39258.0 40309.8 40680.6 29047.0 29579.3 29757.3 29919.4 29938.0 05:28:32 INFO - PROCESS | 6309 | 05:28:32 INFO - PROCESS | 6309 | [#2] jquery.html Cycles:180 Average:30242.03 Median:3106.11 stddev:89026.96 (2866.2%) stddev-sans-first:89248.44 05:28:32 INFO - PROCESS | 6309 | Values: 368.4 370.6 375.3 377.0 395.2 14794.1 18633.3 19430.8 20412.1 20414.7 16230.6 19109.4 19562.3 19655.5 19720.3 5516.6 5763.1 5777.5 5778.3 5778.5 5444.5 5619.1 5619.3 5652.2 5656.0 5610.6 5611.8 5611.8 5617.1 5617.5 5444.4 5571.9 5577.1 5582.3 5609.3 4904.0 4909.8 4913.1 4919.5 4945.3 23982.5 24029.0 24087.2 24099.0 24261.0 2462.7 2626.8 3056.2 3062.4 3149.8 13883.4 14177.8 14572.7 14651.9 14899.0 1887.3 1889.9 1905.3 1914.9 1918.4 50169.5 78103.2 78898.6 79748.6 80909.6 9659.1 9682.7 9713.7 9844.3 9969.5 13868.7 13872.7 13881.4 13947.8 13981.6 7827.2 7926.6 7963.0 8018.6 8098.4 7640.4 7771.6 7917.6 7943.7 7957.7 6399.2 6402.6 6403.7 6434.7 6448.6 1768.4 1780.2 1797.6 1800.1 1819.7 962.6 1033.6 1079.0 1080.3 1134.5 20294.5 20689.6 20712.5 20868.8 20902.6 1016.2 1023.2 1030.6 1036.8 1037.2 9984.5 10438.9 10842.2 11022.9 11529.4 524513.0 532326.0 541448.0 553469.0 558329.0 86529.6 89074.6 91600.0 91798.5 93630.0 1020.8 1044.5 1045.2 1057.8 1065.5 16891.4 17466.1 17830.3 18107.0 18257.6 31760.0 42253.0 42378.8 42577.1 43507.6 58362.4 58466.0 59690.0 59956.0 60303.5 7648.7 7875.9 7956.6 7995.6 8187.7 12065.8 12602.9 13035.3 13316.2 13459.2 11350.8 11453.4 11621.6 11736.8 11865.8 11009.3 11205.2 11340.3 11547.9 11639.7 11443.3 11606.2 11689.6 11772.8 12130.3 7719.9 7831.1 8016.3 8048.6 8058.6 7936.1 10608.5 11263.5 11378.0 11881.2 05:28:32 INFO - PROCESS | 6309 | 05:28:32 INFO - PROCESS | 6309 | [#3] mootools.html Cycles:180 Average:5581.91 Median:5059.49 stddev:3123.83 (61.7%) stddev-sans-first:3116.49 05:28:32 INFO - PROCESS | 6309 | Values: 1361.5 1472.7 1522.5 1674.9 1824.0 7909.0 8324.5 8340.5 8523.3 8540.1 8336.0 8471.0 8472.1 8475.3 8482.7 2281.6 2307.1 2309.2 2310.8 2313.5 2261.2 2333.6 2336.0 2339.6 2343.2 2296.8 2310.3 2323.3 2326.9 2331.8 2244.9 2266.6 2269.6 2270.6 2277.5 2249.7 2250.6 2251.3 2256.6 2265.2 8452.5 8465.5 8507.6 8512.4 8514.8 4947.3 5140.6 5144.6 5145.4 5155.6 3247.6 3382.3 3442.3 3462.9 3477.4 1135.4 1138.4 1199.2 1206.1 1208.9 11567.1 11569.3 11657.2 11660.1 11662.9 4091.3 4278.6 4354.5 4361.2 4487.6 9238.5 9243.4 9244.2 9248.7 9249.8 5129.7 5131.4 5145.0 5149.4 5151.3 4312.5 4673.1 4782.3 4834.3 4850.1 3710.7 3722.9 3831.3 3841.6 3849.6 1134.1 1145.9 1147.3 1149.0 1152.9 794.7 925.5 927.7 928.8 973.7 5438.8 5542.1 5828.9 5838.8 5898.3 925.6 970.8 983.8 998.7 1043.8 3781.9 3871.0 4003.9 4056.6 4083.9 10149.2 10499.6 10507.4 10519.8 10520.4 10443.1 10457.0 10474.2 10493.6 10541.3 7741.7 7859.8 7864.9 7874.0 7881.5 5464.4 5538.2 5625.4 5625.4 5701.1 9568.7 9714.0 9765.8 9771.3 9917.2 9776.0 9800.7 9982.3 10139.6 10151.8 6611.2 6825.1 6918.0 7096.8 7212.1 8107.7 8463.0 8479.6 8479.7 8490.2 7289.2 7335.9 7393.5 7440.7 7608.5 7301.9 7328.2 7412.4 7501.1 7711.2 7021.3 7199.6 7201.7 7216.2 7224.2 4903.4 4955.8 5011.1 5069.5 5161.5 4818.5 4907.9 4986.7 5049.5 5122.2 05:28:32 INFO - PROCESS | 6309 | 05:28:32 INFO - PROCESS | 6309 | [#4] prototype.html Cycles:180 Average:4437.96 Median:4942.53 stddev:1294.79 (26.2%) stddev-sans-first:1260.64 05:28:32 INFO - PROCESS | 6309 | Values: 300.9 315.6 319.7 321.5 322.3 4946.9 5190.6 5234.5 5275.1 5291.2 5197.9 5214.5 5217.0 5231.7 5232.8 4071.3 4075.9 4079.7 4082.4 4084.2 3961.2 4048.5 4061.6 4069.5 4069.9 4006.8 4039.4 4040.5 4047.2 4049.3 4048.9 4051.6 4053.0 4055.0 4063.5 3553.8 3586.1 3586.8 3604.5 3624.9 4889.1 5095.4 5124.8 5183.8 5196.5 4598.1 4600.8 4608.1 4609.3 4636.0 5407.2 5411.2 5437.7 5461.6 5494.5 3665.6 3826.8 3888.1 3902.7 3909.7 5380.8 5539.3 5620.2 5627.0 5629.6 3833.9 3874.5 3882.2 3882.9 3997.2 4016.5 4016.9 4023.8 4034.9 4066.3 4396.1 4544.6 4627.2 4650.3 4658.7 5414.5 5421.2 5426.5 5427.3 5452.5 1334.7 1353.8 1364.7 1367.6 1370.8 829.2 863.1 867.5 892.5 896.6 4687.6 4694.9 4702.4 4702.5 4720.4 4916.6 4941.8 4954.0 4968.3 4973.4 4456.4 4769.0 4773.9 4779.6 4804.8 2594.3 2628.4 2633.8 2643.3 2645.3 5069.9 5073.0 5085.2 5132.2 5162.3 4823.9 4993.6 5042.5 5047.9 5071.6 4988.6 5021.9 5046.5 5075.6 5092.8 4872.9 4942.4 4942.7 4961.3 4983.8 4587.0 4854.4 4889.8 4902.3 4921.7 5531.9 5654.7 5773.0 5786.2 5802.2 3979.6 3979.7 3986.5 4024.9 4025.6 5369.6 5584.6 5588.2 5686.1 5704.9 5490.9 5503.2 5507.0 5511.8 5525.3 5465.5 5494.2 5494.8 5537.6 5538.5 5182.5 5341.3 5412.8 5467.0 5467.9 5479.6 5496.6 5521.9 5530.2 5538.1 5404.2 5405.5 5417.7 5419.0 5465.3 05:28:32 INFO - PROCESS | 6309 | 05:28:32 INFO - PROCESS | 6309 | [#5] yui.html Cycles:180 Average:22289.10 Median:4239.17 stddev:32895.15 (776.0%) stddev-sans-first:32947.87 05:28:32 INFO - PROCESS | 6309 | Values: 803.1 811.6 817.5 824.3 829.6 15528.0 15875.0 16355.3 16388.0 16642.4 16419.6 16447.3 16565.3 16579.3 16656.9 804.8 826.9 828.4 829.4 829.6 664.7 787.2 817.2 818.7 820.2 863.2 870.7 871.1 871.2 871.2 864.6 865.8 866.5 867.1 867.2 508.8 605.5 623.1 632.8 632.9 14239.0 14266.4 14288.3 14289.1 14291.5 13807.0 14344.2 14559.6 14699.9 14701.9 12800.0 12806.6 12869.9 13238.9 13541.7 4149.7 4212.2 4216.7 4233.5 4244.8 81743.6 82173.6 84108.2 84438.0 84554.0 11530.1 11538.5 11589.3 11672.5 11942.6 21552.5 21569.5 21617.5 21638.0 21656.7 8321.4 8330.6 8418.0 8504.1 8754.5 4606.9 4762.3 4795.0 4811.3 4834.7 3458.2 3551.9 3557.0 3560.3 3863.9 688.4 689.4 692.1 699.2 706.2 664.5 708.0 728.6 736.5 745.0 9486.3 9853.2 9855.7 10159.1 10483.3 569.5 659.6 736.3 756.3 757.0 4605.3 5053.6 5067.6 5075.7 5087.1 120855.0 121562.0 126648.0 127475.0 132327.0 106538.5 107348.5 108621.0 108807.0 112776.0 61873.0 97349.5 97563.5 99542.0 102057.0 9428.8 9504.9 9745.7 9825.7 9911.0 56829.4 57034.4 57209.0 57849.0 58157.0 76013.3 82011.8 82457.6 84670.0 84873.2 11796.1 11828.9 11981.2 12052.5 12437.4 13192.9 13432.0 13519.7 14497.9 14651.3 13680.9 13820.6 14147.7 14218.6 14638.1 14246.8 14501.6 14594.4 15663.8 16190.1 13724.7 14488.6 15019.4 15449.5 15638.1 11530.7 11723.6 11820.5 12096.3 12101.7 9995.3 11110.4 11573.7 11910.9 12126.8 05:28:32 INFO - PROCESS | 6309 | -------- Summary: end -------- 05:28:32 INFO - PROCESS | 6309 | 05:28:33 INFO - TEST-INFO | 6309: exit 0 05:28:33 INFO - TEST-OK | dromaeo_css | took 1113841ms 05:28:33 INFO - TEST-START | kraken 05:28:33 INFO - operating with platform_type : linux_ 05:28:33 INFO - Initialising browser for kraken test... 05:28:33 INFO - TEST-INFO | started process 6501 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWk7_QG/profile http://localhost:34035/getInfo.html) 05:28:37 INFO - PROCESS | 6501 | __metrics Screen width/height:1600/1200 05:28:37 INFO - PROCESS | 6501 | colorDepth:24 05:28:37 INFO - PROCESS | 6501 | Browser inner width/height: 1024/697 05:28:37 INFO - PROCESS | 6501 | __metrics 05:28:38 INFO - PROCESS | 6501 | 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" 05:28:38 INFO - PROCESS | 6501 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:28:38 INFO - PROCESS | 6501 | console.error: PushService: 05:28:38 INFO - PROCESS | 6501 | stateChangeProcessEnqueue: Error transitioning state 05:28:38 INFO - PROCESS | 6501 | UnknownError 05:28:39 INFO - TEST-INFO | 6501: exit 0 05:28:39 INFO - Browser initialized. 05:28:39 INFO - Running cycle 1/1 for kraken test... 05:28:39 INFO - TEST-INFO | started process 6582 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWk7_QG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:51 INFO - PROCESS | 6582 | RSS: Main: 155893760 05:28:51 INFO - PROCESS | 6582 | 05:28:53 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34035/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:54 INFO - PROCESS | 6582 | RSS: Main: 212688896 05:28:54 INFO - PROCESS | 6582 | 05:28:57 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34035/tests/kraken/driver.html?testName=audio-dft) 05:28:57 INFO - PROCESS | 6582 | RSS: Main: 191352832 05:28:57 INFO - PROCESS | 6582 | 05:29:00 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34035/tests/kraken/driver.html?testName=audio-fft) 05:29:01 INFO - PROCESS | 6582 | RSS: Main: 268914688 05:29:01 INFO - PROCESS | 6582 | 05:29:03 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34035/tests/kraken/driver.html?testName=audio-oscillator) 05:29:03 INFO - PROCESS | 6582 | RSS: Main: 179830784 05:29:03 INFO - PROCESS | 6582 | 05:29:06 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34035/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:06 INFO - PROCESS | 6582 | RSS: Main: 183988224 05:29:06 INFO - PROCESS | 6582 | 05:29:09 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34035/tests/kraken/driver.html?testName=imaging-darkroom) 05:29:10 INFO - PROCESS | 6582 | RSS: Main: 220880896 05:29:10 INFO - PROCESS | 6582 | 05:29:13 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34035/tests/kraken/driver.html?testName=imaging-desaturate) 05:29:14 INFO - PROCESS | 6582 | RSS: Main: 220008448 05:29:14 INFO - PROCESS | 6582 | 05:29:17 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34035/tests/kraken/driver.html?testName=json-parse-financial) 05:29:17 INFO - PROCESS | 6582 | RSS: Main: 220020736 05:29:17 INFO - PROCESS | 6582 | 05:29:19 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34035/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:19 INFO - PROCESS | 6582 | RSS: Main: 183828480 05:29:19 INFO - PROCESS | 6582 | 05:29:21 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34035/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:22 INFO - PROCESS | 6582 | RSS: Main: 189546496 05:29:22 INFO - PROCESS | 6582 | 05:29:24 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34035/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:24 INFO - PROCESS | 6582 | RSS: Main: 193798144 05:29:24 INFO - PROCESS | 6582 | 05:29:27 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34035/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:27 INFO - PROCESS | 6582 | RSS: Main: 187330560 05:29:27 INFO - PROCESS | 6582 | 05:29:30 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34035/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:31 INFO - PROCESS | 6582 | RSS: Main: 192290816 05:29:31 INFO - PROCESS | 6582 | 05:29:32 INFO - PROCESS | 6582 | Cycle 1(1): loaded http://localhost:34035/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34035/tests/kraken/driver.html?testName=ai-astar) 05:29:33 INFO - PROCESS | 6582 | RSS: Main: 243531776 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | __start_tp_report 05:29:33 INFO - PROCESS | 6582 | _x_x_mozilla_page_load 05:29:33 INFO - PROCESS | 6582 | _x_x_mozilla_page_load_details 05:29:33 INFO - PROCESS | 6582 | |i|pagename|runs| 05:29:33 INFO - PROCESS | 6582 | |0;ai-astar;102;111;112;112;110;111;109;110;110;112 05:29:33 INFO - PROCESS | 6582 | |1;audio-beat-detection;142;117;117;171;119;120;146;117;149;116 05:29:33 INFO - PROCESS | 6582 | |2;audio-dft;160;161;161;159;161;161;161;162;160;161 05:29:33 INFO - PROCESS | 6582 | |3;audio-fft;74;74;76;124;75;75;74;74;109;74 05:29:33 INFO - PROCESS | 6582 | |4;audio-oscillator;84;83;122;84;83;84;115;83;84;132 05:29:33 INFO - PROCESS | 6582 | |5;imaging-gaussian-blur;117;117;116;117;117;116;117;116;117;117 05:29:33 INFO - PROCESS | 6582 | |6;imaging-darkroom;146;146;145;145;145;147;146;150;146;146 05:29:33 INFO - PROCESS | 6582 | |7;imaging-desaturate;95;95;94;95;95;94;95;94;96;95 05:29:33 INFO - PROCESS | 6582 | |8;json-parse-financial;53;53;53;52;53;55;55;52;52;52 05:29:33 INFO - PROCESS | 6582 | |9;json-stringify-tinderbox;63;63;62;62;63;61;62;62;62;63 05:29:33 INFO - PROCESS | 6582 | |10;stanford-crypto-aes;76;76;75;75;75;76;76;75;76;80 05:29:33 INFO - PROCESS | 6582 | |11;stanford-crypto-ccm;135;120;121;121;127;117;120;128;165;124 05:29:33 INFO - PROCESS | 6582 | |12;stanford-crypto-pbkdf2;160;158;160;160;161;156;156;155;159;161 05:29:33 INFO - PROCESS | 6582 | |13;stanford-crypto-sha256-iterative;63;62;63;62;62;64;63;63;62;64 05:29:33 INFO - PROCESS | 6582 | __end_tp_report 05:29:33 INFO - PROCESS | 6582 | __start_cc_report 05:29:33 INFO - PROCESS | 6582 | _x_x_mozilla_cycle_collect,1642 05:29:33 INFO - PROCESS | 6582 | __end_cc_report 05:29:33 INFO - PROCESS | 6582 | __startTimestamp1470054573165__endTimestamp 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | ------- Summary: start ------- 05:29:33 INFO - PROCESS | 6582 | Number of tests: 14 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:2.96 (2.7%) stddev-sans-first:1.09 05:29:33 INFO - PROCESS | 6582 | Values: 102.0 111.0 112.0 112.0 110.0 111.0 109.0 110.0 110.0 112.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:131.00 stddev:19.28 (14.7%) stddev-sans-first:20.07 05:29:33 INFO - PROCESS | 6582 | Values: 142.0 117.0 117.0 171.0 119.0 120.0 146.0 117.0 149.0 116.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 05:29:33 INFO - PROCESS | 6582 | Values: 160.0 161.0 161.0 159.0 161.0 161.0 161.0 162.0 160.0 161.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#3] audio-fft Cycles:10 Average:82.90 Median:74.00 stddev:18.07 (24.4%) stddev-sans-first:18.88 05:29:33 INFO - PROCESS | 6582 | Values: 74.0 74.0 76.0 124.0 75.0 75.0 74.0 74.0 109.0 74.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:83.50 stddev:19.47 (23.3%) stddev-sans-first:20.21 05:29:33 INFO - PROCESS | 6582 | Values: 84.0 83.0 122.0 84.0 83.0 84.0 115.0 83.0 84.0 132.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 05:29:33 INFO - PROCESS | 6582 | Values: 117.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:1.48 (1.0%) stddev-sans-first:1.56 05:29:33 INFO - PROCESS | 6582 | Values: 146.0 146.0 145.0 145.0 145.0 147.0 146.0 150.0 146.0 146.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 05:29:33 INFO - PROCESS | 6582 | Values: 95.0 95.0 94.0 95.0 95.0 94.0 95.0 94.0 96.0 95.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:1.15 (2.2%) stddev-sans-first:1.22 05:29:33 INFO - PROCESS | 6582 | Values: 53.0 53.0 53.0 52.0 53.0 55.0 55.0 52.0 52.0 52.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:62.50 stddev:0.67 (1.1%) stddev-sans-first:0.67 05:29:33 INFO - PROCESS | 6582 | Values: 63.0 63.0 62.0 62.0 63.0 61.0 62.0 62.0 62.0 63.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:76.00 stddev:1.49 (2.0%) stddev-sans-first:1.58 05:29:33 INFO - PROCESS | 6582 | Values: 76.0 76.0 75.0 75.0 75.0 76.0 76.0 75.0 76.0 80.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:125.50 stddev:14.07 (11.2%) stddev-sans-first:14.68 05:29:33 INFO - PROCESS | 6582 | Values: 135.0 120.0 121.0 121.0 127.0 117.0 120.0 128.0 165.0 124.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.60 Median:160.00 stddev:2.22 (1.4%) stddev-sans-first:2.30 05:29:33 INFO - PROCESS | 6582 | Values: 160.0 158.0 160.0 160.0 161.0 156.0 156.0 155.0 159.0 161.0 05:29:33 INFO - PROCESS | 6582 | 05:29:33 INFO - PROCESS | 6582 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:0.79 (1.3%) stddev-sans-first:0.83 05:29:33 INFO - PROCESS | 6582 | Values: 63.0 62.0 63.0 62.0 62.0 64.0 63.0 63.0 62.0 64.0 05:29:33 INFO - PROCESS | 6582 | -------- Summary: end -------- 05:29:33 INFO - PROCESS | 6582 | 05:29:34 INFO - TEST-INFO | 6582: exit 0 05:29:34 INFO - TEST-OK | kraken | took 60323ms 05:29:34 INFO - SUITE-END | took 1174s 05:29:34 INFO - Completed test suite (00:19:34) 05:29:34 INFO - javascript benchmark 05:29:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3011.457937571237, 3053.709897559731, 3078.7690923180753, 3084.2906131589734, 3092.5825013623153, 5242.397124330703, 5292.682439053659, 5301.363836339639, 5375.220593014015, 5402.567794576439, 5340.225667278242, 5348.812791552296, 5355.08963476209, 5410.864728381793, 5419.403865574784, 4868.902621947559, 4890.510948905107, 4912.803487860499, 4920.483349248331, 5032.471590483002, 4946.0, 4981.028699403618, 5000.274960130779, 5009.198528235483, 5013.197888337867, 4791.257355109981, 4804.927926081112, 4842.854714358539, 4900.313956046157, 4971.776270067838, 4791.760411979387, 4802.207635740098, 4838.153323168432, 4892.168331383673, 4938.4814594467425, 4864.36763220779, 4904.803807847682, 4908.705477671351, 4914.631402644816, 4922.163232250526, 5208.401033881077, 5259.605529585297, 5348.0, 5411.350637923435, 5431.995080910045, 5717.542596592262, 5766.106253530709, 5768.077107662753, 5776.855578610526, 5777.855553611152, 5761.884762304772, 5818.505427038707, 5820.767169313222, 5826.126081087828, 5828.067509198508, 5242.79028838846, 5360.249565060894, 5388.649737767111, 5396.055690254189, 5417.566594672417, 5954.46409823118, 6002.729877155539, 6003.159557661931, 6007.429294217042, 6010.038393856962, 5752.309722833287, 5759.884802303931, 5762.567807414461, 5763.827085187451, 5766.711664416763, 5562.193481945176, 5674.148877668383, 5696.51579615729, 5719.513841323451, 5824.854378640483, 5725.799597014085, 5728.828135155952, 5778.739956701958, 5786.305643322744, 5789.218455508537, 5702.781298933728, 5710.086386178192, 5736.0, 5747.3677895431465, 5751.626144300607, 4304.677149213821, 4346.261135606955, 4429.623482004002, 4434.401355816924, 4481.775911204427, 3876.321643712339, 3911.2764138633875, 3918.040080180374, 3931.095847954929, 3955.129871428281, 5356.517913387814, 5381.9461805382225, 5388.380336261302, 5388.757505912165, 5391.649542779707, 5531.3915469298345, 5655.179998900218, 5758.3665796762325, 5774.451427114502, 5817.127430885316, 5374.892502149935, 5382.703951282717, 5399.568034557226, 5423.566114710814, 5428.158635411518, 4978.5768209701855, 4979.3526841510375, 4981.501849815062, 4982.078315511642, 5022.522860328263, 5782.421757824268, 5859.267591551142, 5931.822045338647, 5934.347221805598, 5945.286565612154, 5738.340090889435, 5768.480836724715, 5775.595708300379, 5853.882922341614, 5855.063189889597, 5913.05391137416, 5948.375420580776, 5966.0, 5973.671448070398, 5976.0, 5469.480399362054, 5607.0, 5611.158326250932, 5659.207710920557, 5671.17767923657, 5726.312842458765, 5810.418958104325, 5820.30156381237, 5841.620294680833, 5851.590388672752, 5651.689157096234, 5694.544436445157, 5695.288088988877, 5697.487226149667, 5710.65736055838, 5376.623636345575, 5532.751026203911, 5686.9715651421475, 5688.914666280092, 5691.373948865705, 5484.478974497416, 5534.557235421237, 5544.279243698294, 5546.58400619947, 5602.411746766531, 5582.134769110794, 5630.5214056805635, 5695.430456954271, 5706.771729130788, 5710.114932185518, 5403.486668766461, 5418.512333889968, 5501.5323697486165, 5510.559155267489, 5512.952539017574], "value": 5300.780985663444, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.3467120838285, 615.258613370888, 632.5256057956531, 635.1108448172558, 651.5960104735059, 33446.66553334466, 44916.0, 46039.76980115095, 46149.538504615026, 46207.76896115515, 46814.0, 47315.053698926, 47391.0, 47399.28901066487, 47421.525784742145, 9040.050794666548, 9163.862542061874, 9175.770605734877, 9184.311176661744, 9200.125988031126, 8937.240334571537, 9011.54942252888, 9034.728958131267, 9035.864462033074, 9044.728658140233, 9032.909670903306, 9076.727698169065, 9083.863742043875, 9086.091390860895, 9086.954565227164, 9045.547722613843, 9067.138621830916, 9087.136722011399, 9093.04523025086, 9095.590698418553, 9052.954735226282, 9116.726498205064, 9125.041870603558, 9127.361084724072, 9136.177744003071, 44517.0, 44713.776431117636, 44805.103897922185, 44892.3266151008, 44960.0, 3540.044188069207, 3820.102275965146, 4072.6131017553284, 4278.2085314216965, 4406.8457603984025, 33793.6620633793, 34213.82893085544, 34489.0, 35226.47160292598, 36046.0, 4376.452943382077, 4408.175488507892, 4454.0, 4579.610733087691, 4604.585587297126, 250193.7490312555, 250651.0, 251056.0, 251177.0, 251394.0, 61855.38144618496, 61914.0, 62107.0, 62394.376056238856, 62562.68718656377, 65471.67264163744, 65586.34413655802, 65660.0, 65664.34335656678, 65760.67119664371, 12995.28525931064, 16995.150242487827, 17126.828731712772, 17279.82720172782, 17437.65124697499, 12666.683332916751, 12684.42920068599, 12717.300548469922, 12729.236245825095, 12754.61736147917, 18738.812611873705, 18857.0, 18874.0, 18921.337753178574, 18958.431247062334, 5800.651960882361, 6587.670616469158, 6724.562903411263, 6883.346082122191, 6901.309869013058, 987.6049580167846, 1022.9488525573692, 1045.8849526552074, 1098.8571485706807, 1168.415792103948, 33068.66931330656, 33106.3378732424, 33127.83436082852, 33156.3368732624, 33855.830720846236, 1101.8485682461846, 1143.7255058785997, 1165.3648761425043, 1201.3152503073165, 1236.7402845402387, 17326.574279600867, 17481.82518174802, 17482.688798340176, 17536.561585960197, 17642.911785441247, 472296.0, 473142.0, 473444.0, 475084.0, 475095.0, 59940.0, 60334.094988575205, 60415.093773593435, 60454.0, 60502.69748651228, 1217.7807994561065, 1335.5726167626267, 1362.4413990264143, 1367.1045465220298, 1381.709840933395, 31709.682903171597, 34963.0, 35360.64639353574, 35486.645133548336, 35671.46492802558, 55513.444865550824, 56481.71759141178, 56513.434865650815, 56564.0, 56639.0, 470947.0, 479397.0, 482128.0, 482581.0, 483814.0, 43967.340489893315, 45559.31661025021, 46146.0, 48535.5146448531, 50669.23996140135, 50777.0, 50806.74596626993, 51406.0, 52094.739526302124, 52240.73879630578, 42525.149497010505, 42690.0, 43664.56335436732, 44158.55841441544, 45336.0, 42959.31539416226, 43711.0, 43974.78012609916, 45027.77486112549, 45374.546254538356, 43038.78480607577, 43910.78044609884, 44182.0, 44801.327980080976, 45030.324545132506, 37399.0, 39043.41434878536, 39258.0, 40309.798451007555, 40680.59319406768, 29047.0, 29579.26136222579, 29757.256068598457, 29919.401611968075, 29938.0], "value": 20955.561829512422, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.3847973883614, 370.64973599948024, 375.32441605110796, 376.95288088988866, 395.2411370169275, 14794.112353258799, 18633.254669813206, 19430.805691943075, 20412.081456334465, 20414.69377959332, 16230.594235144128, 19109.426717198505, 19562.31531896384, 19655.508612284666, 19720.30978915738, 5516.641418307807, 5763.135529670562, 5777.45114214149, 5778.27771528559, 5778.5377169826525, 5444.537214336786, 5619.129034999582, 5619.325680918296, 5652.180433837091, 5656.038473459501, 5610.635308704942, 5611.80358687446, 5611.831645050655, 5617.0731829248125, 5617.466340697648, 5444.373897001856, 5571.9442805572035, 5577.05190117681, 5582.330120385539, 5609.3268807743125, 4903.975480122613, 4909.754512274372, 4913.14020046494, 4919.458859525449, 4945.332380128672, 23982.52034959291, 24029.0, 24087.156949506858, 24099.0, 24261.029558817627, 2462.704475462956, 2626.8423894566195, 3056.2359410147465, 3062.4028314478687, 3149.811011339327, 13883.37524811386, 14177.78733319001, 14572.70854582913, 14651.926740366336, 14899.031562948374, 1887.3205645967441, 1889.896055716935, 1905.3331334033116, 1914.8946807925558, 1918.424472658197, 50169.498305017216, 78103.21896781072, 78898.60550697267, 79748.60125699392, 80909.59545202296, 9659.082387173208, 9682.70951871445, 9713.660021899199, 9844.310898237198, 9969.451680157661, 13868.653283667787, 13872.653183670489, 13881.4447422104, 13947.790783138262, 13981.580552583438, 7827.178146294671, 7926.643301051467, 7963.044434667877, 8018.5990700464745, 8098.433109682265, 7640.426967977424, 7771.572563508948, 7917.643706033243, 7943.721969731032, 7957.681692732226, 6399.176234773067, 6402.583832050902, 6403.743850245938, 6434.7104380303, 6448.613083215022, 1768.4341010876653, 1780.1900135438345, 1797.5845892803516, 1800.1359347513267, 1819.6691873341626, 962.6005207838808, 1033.5762337441613, 1079.0019232210175, 1080.303204433152, 1134.478140055567, 20294.492637684176, 20689.58620827605, 20712.48218794542, 20868.791312086683, 20902.58194836095, 1016.1718199667248, 1023.1661196125134, 1030.6341248856697, 1036.7563622548696, 1037.1702637889657, 9984.500774961369, 10438.947805260925, 10842.186835987333, 11022.889771102347, 11529.365884876244, 524513.0, 532326.0, 541448.0, 553469.0, 558329.0, 86529.56735216283, 89074.55462722645, 91600.0, 91798.54100729454, 93630.0, 1020.8009438159636, 1044.5456226541478, 1045.1795340657548, 1057.8095942730383, 1065.4512925843196, 16891.408800691916, 17466.061539076774, 17830.286788528312, 18107.0, 18257.634847303245, 31760.0, 42253.0, 42378.78810605927, 42577.148457031304, 43507.564924351616, 58362.4163758374, 58466.0, 59690.0, 59956.0, 60303.49241269004, 7648.73229436967, 7875.921240787518, 7956.562389068657, 7995.600219989094, 8187.672493100209, 12065.758684826078, 12602.87397126017, 13035.348232588522, 13316.202312687663, 13459.192448453125, 11350.772984540428, 11453.427328633701, 11621.593244236412, 11736.823947640622, 11865.822012669643, 11009.339439633648, 11205.215634905479, 11340.262882912584, 11547.942260288644, 11639.6508104757, 11443.313401195956, 11606.187566870238, 11689.590864319707, 11772.823407649063, 12130.272183669009, 7719.922800772145, 7831.099423566135, 8016.318612917968, 8048.5975701215875, 8058.597070146587, 7936.127026027017, 10608.522616482123, 11263.549458021915, 11378.0, 11881.21781732747], "value": 9263.168949922238, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.5098564516766, 1472.7128209999055, 1522.528016314942, 1674.9413770518033, 1823.9785719996803, 7909.0, 8324.458910170843, 8340.499570025802, 8523.275521580657, 8540.060593334743, 8335.958320208405, 8471.0, 8472.110428405022, 8475.32197424206, 8482.66069357225, 2281.5665023645456, 2307.077169132344, 2309.2379514760087, 2310.8266879983985, 2313.5141620259683, 2261.2424837679396, 2333.556624241397, 2336.0, 2339.6256598944174, 2343.168175297762, 2296.7932886040253, 2310.295359915234, 2323.3146221864504, 2326.9418264543438, 2331.7901388875, 2244.86530808152, 2266.580682573729, 2269.568781931436, 2270.5685919675207, 2277.498950230955, 2249.6962910007105, 2250.606143924823, 2251.324602619208, 2256.605094108541, 2265.161890100673, 8452.450590711645, 8465.53439560824, 8507.574621268912, 8512.44669096513, 8514.829703405898, 4947.332110165154, 5140.563052140573, 5144.588432925357, 5145.3568303962, 5155.587552995751, 3247.561579186803, 3382.2897191589796, 3442.277121804424, 3462.8614855405754, 3477.426224672926, 1135.409587014756, 1138.402338772143, 1199.2205066706624, 1206.1315852586124, 1208.9032877369789, 11567.13246506507, 11569.305841649528, 11657.242279251821, 11660.125490588154, 11662.88337116635, 4091.3044782387065, 4278.614924656796, 4354.455693038371, 4361.171377438277, 4487.640988720895, 9238.491882946368, 9243.352965292364, 9244.214241789523, 9248.722538323862, 9249.768755781159, 5129.666571672829, 5131.435542090367, 5145.0, 5149.382074151126, 5151.330327057459, 4312.547182545787, 4673.065386922629, 4782.306565547976, 4834.347363106006, 4850.054239423279, 3710.740248182601, 3722.9069273268383, 3831.2529056834205, 3841.615838416136, 3849.5957924417535, 1134.1493879590307, 1145.8682251541013, 1147.2944139354238, 1148.9827652585218, 1152.9250598711058, 794.6662401791261, 925.5002298758595, 927.6567669962158, 928.7817362919709, 973.7127547373542, 5438.755256013489, 5542.11326187808, 5828.854278643068, 5838.766449342063, 5898.292204935349, 925.5881132895797, 970.8009857979098, 983.7884854756262, 998.6604554451376, 1043.7599352149048, 3781.8865434037025, 3871.0, 4003.939940900889, 4056.553779084298, 4083.89790255246, 10149.238807089498, 10499.632512862014, 10507.422091785025, 10519.789604207874, 10520.421376824195, 10443.060124588823, 10457.0, 10474.214433917487, 10493.580256789795, 10541.314814537183, 7741.690332386642, 7859.842803143905, 7864.921350786533, 7874.01574803161, 7881.527108373516, 5464.398916119303, 5538.196961440649, 5625.353084395183, 5625.353084395347, 5701.116326969326, 9568.66509672186, 9713.951430242803, 9765.80468390614, 9771.31600787938, 9917.15704165154, 9776.03857307585, 9800.65697700577, 9982.251331150337, 10139.64511242103, 10151.847722284025, 6611.239707433514, 6825.146856642919, 6917.965410172917, 7096.7516136934955, 7212.062431883822, 8107.675692972215, 8462.957685211535, 8479.61841717112, 8479.745607631781, 8490.23587877094, 7289.161746399232, 7335.926640733738, 7393.482456228013, 7440.665170067252, 7608.543487390774, 7301.853962920818, 7328.193898671036, 7412.4070074394995, 7501.062367204099, 7711.190325016017, 7021.262767409348, 7199.604021778852, 7201.675924583302, 7216.170140433917, 7224.169220539705, 4903.41159060915, 4955.8017679292425, 5011.123053465702, 5069.493050695049, 5161.483851614808, 4818.518148185153, 4907.901841963212, 4986.700797952134, 5049.495050495068, 5122.154844450624], "value": 4498.118000961399, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.87964814074365, 315.6259832098963, 319.69628852590046, 321.50167240776807, 322.31347230399246, 4946.876328091799, 5190.558802501787, 5234.5027222413855, 5275.129603615399, 5291.206319052144, 5197.870053248671, 5214.523101526773, 5216.973915130419, 5231.738413079341, 5232.816851410202, 4071.2671719090554, 4075.8981025474454, 4079.714419990602, 4082.387641853716, 4084.24441478326, 3961.1681546875193, 4048.4939382577177, 4061.5532291447917, 4069.532003819554, 4069.877903662895, 4006.8397264109544, 4039.3738970459626, 4040.5353384360883, 4047.1989964465824, 4049.311617025113, 4048.9190216195807, 4051.574584668597, 4052.959470405288, 4054.965983674174, 4063.512378514597, 3553.751237413382, 3586.1214002569277, 3586.8206589670413, 3604.4953706481115, 3624.9275014499563, 4889.144399730033, 5095.363079615048, 5124.795008199668, 5183.844484665466, 5196.53231209193, 4598.149342371672, 4600.769961501945, 4608.101420223058, 4609.262517997105, 4636.0, 5407.242985981966, 5411.215373770781, 5437.700926449043, 5461.563074953994, 5494.5054945055135, 3665.6151104133946, 3826.827792749333, 3888.0862997195636, 3902.7268091233627, 3909.7263191576603, 5380.838574842761, 5539.335279766454, 5620.241267428885, 5626.971865140689, 5629.57778166635, 3833.9041523961564, 3874.4575759393706, 3882.165334453106, 3882.941755873664, 3997.2405243003736, 4016.477857878457, 4016.8995775105855, 4023.8189281482405, 4034.919301613951, 4066.2883995301295, 4396.142752163298, 4544.613707834805, 4627.236505976476, 4650.348951146842, 4658.650601204923, 5414.458554144554, 5421.186821976735, 5426.511613954684, 5427.348718153768, 5452.509274165344, 1334.7463981843418, 1353.78339465685, 1364.7338768939962, 1367.596559015093, 1370.8080868678394, 829.1708291708292, 863.1196179896596, 867.4925168776336, 892.5314210039782, 896.5965315608003, 4687.6249900007915, 4694.85444752017, 4702.365180700562, 4702.459217190067, 4720.409948756405, 4916.582090522345, 4941.75291235444, 4954.0, 4968.27959945813, 4973.403191617029, 4456.3538286948215, 4768.976155119202, 4773.904521909542, 4779.593734532534, 4804.77781273252, 2594.299539124426, 2628.4217472156097, 2633.8024648151463, 2643.299525625711, 2645.3254420122744, 5069.8592816615965, 5072.974635126875, 5085.211792172146, 5132.230165475134, 5162.3030890830005, 4823.877493902672, 4993.601791498386, 5042.470540593258, 5047.899042019212, 5071.5689166420525, 4988.625853061035, 5021.870079232143, 5046.520580544842, 5075.568576671024, 5092.821751238689, 4872.904651000104, 4942.4069111705385, 4942.654014218971, 4961.280614310833, 4983.775730092227, 4587.013792034729, 4854.441739200037, 4889.755512224303, 4902.264660300983, 4921.744955036443, 5531.944680553143, 5654.6889921054735, 5773.0, 5786.189933409242, 5802.187693722966, 3979.567355751869, 3979.6617287530885, 3986.5216174059296, 4024.879253622396, 4025.6175663311938, 5369.597280203922, 5584.581156413366, 5588.189712491585, 5686.061799802987, 5704.857378565569, 5490.945090549043, 5503.229547863222, 5507.0, 5511.751971161227, 5525.336959564878, 5465.508104270635, 5494.1758736189895, 5494.80768173128, 5537.640053396516, 5538.529225015919, 5182.455842136521, 5341.305630268041, 5412.78348866041, 5467.0, 5467.890642187054, 5479.561555140349, 5496.642718223303, 5521.917171242354, 5530.225768392509, 5538.141588053858, 5404.216388623706, 5405.540529055074, 5417.674939503642, 5418.972905135449, 5465.289512363367], "value": 4022.170039112985, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.0965164190286, 811.6144831205178, 817.513579420245, 824.3405275779375, 829.6440896573358, 15528.0, 15875.0, 16355.264013119408, 16388.016718996852, 16642.417515386933, 16419.58951026222, 16447.2598733057, 16565.337386504554, 16579.25393357299, 16656.916715416406, 804.8309854930471, 826.859433896236, 828.362161135925, 829.4069740135802, 829.5561874397199, 664.6942406492994, 787.2127872127872, 817.15832692327, 818.7298191596789, 820.2207902492626, 863.2489733931457, 870.6952566601702, 871.0766587417357, 871.2289623683023, 871.2463718883153, 864.5590748718137, 865.7878819689184, 866.5017614871412, 867.1285358215017, 867.2281669314284, 508.8015673887187, 605.4883623338272, 623.0560700538688, 632.8133200705803, 632.89873620221, 14239.0, 14266.429342826275, 14288.285585720672, 14289.142651440947, 14291.499797507038, 13807.0, 14344.211068391234, 14559.563213103624, 14699.853001470063, 14701.852981470262, 12800.0, 12806.61580152597, 12869.944056636412, 13238.933805331008, 13541.729165416735, 4149.730267532631, 4212.157568486315, 4216.683748718859, 4233.51314598822, 4244.830206791725, 81743.59128204321, 82173.58913205455, 84108.15891841064, 84438.0, 84554.0, 11530.077593792477, 11538.538458461735, 11589.304641721523, 11672.474738636782, 11942.641720748392, 21552.461188470414, 21569.460763481038, 21617.459563510725, 21638.0, 21656.675149872764, 8321.417500774887, 8330.583470826434, 8418.0, 8504.149585041447, 8754.47473151613, 4606.861794146181, 4762.261849413347, 4795.0, 4811.254255590389, 4834.661573689878, 3458.2219000724635, 3551.94672079919, 3556.982215088908, 3560.2701446203464, 3863.942040869389, 688.4458011300851, 689.427774946793, 692.1486571517062, 699.1784653032666, 706.20199174932, 664.5115839857797, 707.9716811327593, 728.5956294256632, 736.5028605691137, 744.985100297991, 9486.288528360401, 9853.24113228209, 9855.655052073143, 10159.136473399843, 10483.318584291997, 569.5073761196618, 659.6174218952996, 736.2674139231499, 756.2664215710752, 756.9857482303158, 4605.263157894721, 5053.5957123430035, 5067.569256613156, 5075.695458272442, 5087.109755792795, 120855.0, 121562.0, 126648.0, 127475.0, 132327.0, 106538.46730766297, 107348.46325768478, 108621.0, 108807.0, 112776.0, 61873.0, 97349.51325243329, 97563.51218244006, 99542.00457995472, 102057.0, 9428.764280893032, 9504.857427138737, 9745.707628771148, 9825.656102036395, 9911.0, 56829.431705682415, 57034.43724502113, 57209.0, 57849.0, 58157.0, 76013.25415182875, 82011.79882011752, 82457.58771206345, 84670.0, 84873.15126848653, 11796.056315494912, 11828.881711182778, 11981.161318707607, 12052.457639406422, 12437.378131093228, 13192.868071319548, 13432.0, 13519.662008449868, 14497.92751036238, 14651.340689669203, 13680.863191367958, 13820.585382438543, 14147.717045658823, 14218.644533886736, 14638.121712697272, 14246.786298205743, 14501.637459063608, 14594.416223350947, 15663.843361566238, 16190.109543975195, 13724.65688357759, 14488.637784055483, 15019.399224030916, 15449.5365139046, 15638.139902305482, 11530.711732206762, 11723.589674361356, 11820.468078936297, 12096.334701591144, 12101.69745756363, 9995.250356223167, 11110.388928608681, 11573.7106572333, 11910.940445298065, 12126.818097728717], "value": 7835.676165036103, "unit": "score"}], "name": "dromaeo_css", "value": 7255.535529314202, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 112.0, 112.0, 110.0, 111.0, 109.0, 110.0, 110.0, 112.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 117.0, 171.0, 119.0, 120.0, 146.0, 117.0, 149.0, 116.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 159.0, 161.0, 161.0, 161.0, 162.0, 160.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 124.0, 75.0, 75.0, 74.0, 74.0, 109.0, 74.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 122.0, 84.0, 83.0, 84.0, 115.0, 83.0, 84.0, 132.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 145.0, 147.0, 146.0, 150.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 96.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 52.0, 53.0, 55.0, 55.0, 52.0, 52.0, 52.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 62.0, 63.0, 61.0, 62.0, 62.0, 62.0, 63.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 75.0, 75.0, 76.0, 76.0, 75.0, 76.0, 80.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 120.0, 121.0, 121.0, 127.0, 117.0, 120.0, 128.0, 165.0, 124.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 158.0, 160.0, 160.0, 161.0, 156.0, 156.0, 155.0, 159.0, 161.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 62.0, 62.0, 64.0, 63.0, 63.0, 62.0, 64.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1478.4999999999998, "alertThreshold": 2.0}]} 05:29:34 INFO - Return code: 0 05:29:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:29:34 INFO - # TBPL SUCCESS # 05:29:34 INFO - Running post-action listener: _resource_record_post_action 05:29:34 INFO - [mozharness: 2016-08-01 12:29:34.463335Z] Finished run-tests step (success) 05:29:34 INFO - Running post-run listener: _resource_record_post_run 05:29:34 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 541413376; Read time: 16; Write time: 489456 05:29:34 INFO - TinderboxPrint: CPU usage
13.1% 05:29:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 05:29:34 INFO - TinderboxPrint: I/O write bytes / time
541,413,376 / 489,456 05:29:34 INFO - TinderboxPrint: CPU idle
8,263.1 (86.9%) 05:29:34 INFO - TinderboxPrint: CPU user
1,197.0 (12.6%) 05:29:34 INFO - TinderboxPrint: Swap in / out
0 / 0 05:29:34 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247640064; Read time: 0; Write time: 255308 05:29:34 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 293404672; Read time: 0; Write time: 234092 05:29:35 INFO - Running post-run listener: _upload_blobber_files 05:29:35 INFO - Blob upload gear active. 05:29:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:35 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 05:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:29:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:29:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:29:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:36 INFO - (blobuploader) - INFO - Done attempting. 05:29:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:29:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:29:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:40 INFO - (blobuploader) - INFO - Done attempting. 05:29:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:29:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:29:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:41 INFO - (blobuploader) - INFO - Done attempting. 05:29:41 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:41 INFO - Return code: 0 05:29:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4aededaf75de1308fbad05552e5facab6e9344667d930ab1373e92dfe7551f1102d214970ab6153e178461c5c162c73406eb735806bd69a1a77f8f744f5cc35f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea4be4ddf0d46cce81c9c2cfc9342ee1265981105b72bc8ae926fcc586f8bfea10cbbbf6d4f90d93af32f8e5a5ef5128b304df1cfad811237c2621b2fb5e73d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3747cc43988032fa048e075edab1aa88d71b4018a197975f1103f73523f671506be398c2739bed258a60bd26241fb85d3006f34a2a0103bcfeea2f942de9da27"} 05:29:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:41 INFO - Contents: 05:29:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4aededaf75de1308fbad05552e5facab6e9344667d930ab1373e92dfe7551f1102d214970ab6153e178461c5c162c73406eb735806bd69a1a77f8f744f5cc35f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea4be4ddf0d46cce81c9c2cfc9342ee1265981105b72bc8ae926fcc586f8bfea10cbbbf6d4f90d93af32f8e5a5ef5128b304df1cfad811237c2621b2fb5e73d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3747cc43988032fa048e075edab1aa88d71b4018a197975f1103f73523f671506be398c2739bed258a60bd26241fb85d3006f34a2a0103bcfeea2f942de9da27"} 05:29:41 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:41 INFO - Copying logs to upload dir... 05:29:41 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.690859 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-08-01 05:29:41.517596) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 05:29:41.520778) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4aededaf75de1308fbad05552e5facab6e9344667d930ab1373e92dfe7551f1102d214970ab6153e178461c5c162c73406eb735806bd69a1a77f8f744f5cc35f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea4be4ddf0d46cce81c9c2cfc9342ee1265981105b72bc8ae926fcc586f8bfea10cbbbf6d4f90d93af32f8e5a5ef5128b304df1cfad811237c2621b2fb5e73d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3747cc43988032fa048e075edab1aa88d71b4018a197975f1103f73523f671506be398c2739bed258a60bd26241fb85d3006f34a2a0103bcfeea2f942de9da27"} build_url:https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011212 build_url: 'https://queue.taskcluster.net/v1/task/YBaJ3zAKS5SC-LW4_xv6fw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4aededaf75de1308fbad05552e5facab6e9344667d930ab1373e92dfe7551f1102d214970ab6153e178461c5c162c73406eb735806bd69a1a77f8f744f5cc35f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea4be4ddf0d46cce81c9c2cfc9342ee1265981105b72bc8ae926fcc586f8bfea10cbbbf6d4f90d93af32f8e5a5ef5128b304df1cfad811237c2621b2fb5e73d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3747cc43988032fa048e075edab1aa88d71b4018a197975f1103f73523f671506be398c2739bed258a60bd26241fb85d3006f34a2a0103bcfeea2f942de9da27"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 05:29:41.546078) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 05:29:41.546453) ========= 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=31acecf42b6a79ec49471d4a000001bb-1470051752.851274-416744143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 05:29:41.631238) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 05:29:41.631604) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 05:29:41.631903) ========= ========= Total master_lag: 0.20 =========