builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-038 starttime: 1465193305.39 results: success (0) buildid: 20160605222532 builduid: d91ea748e74840eba03f006164e6976d revision: 824355c9efe23d83f7e67a4b2c9e0e837f7c7771 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:25.393744) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:25.394179) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:25.394447) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:25.413239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:25.413520) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-05 23:08:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-06-05 23:08:26 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126516 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:25.554731) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:25.555002) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019249 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:25.603798) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:25.604099) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 824355c9efe23d83f7e67a4b2c9e0e837f7c7771 --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 824355c9efe23d83f7e67a4b2c9e0e837f7c7771 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-05 23:08:26,253 truncating revision to first 12 chars 2016-06-05 23:08:26,253 Setting DEBUG logging. 2016-06-05 23:08:26,253 attempt 1/10 2016-06-05 23:08:26,253 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/824355c9efe2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-05 23:08:26,674 unpacking tar archive at: fx-team-824355c9efe2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.737773 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:26.355106) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:26.355378) ========= script_repo_revision: 824355c9efe23d83f7e67a4b2c9e0e837f7c7771 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:26.355810) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:26.356068) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 23:08:26.364625) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-06-05 23:08:26.364904) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:08:27 INFO - MultiFileLogger online at 20160605 23:08:27 in /builds/slave/test 23:08:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 23:08:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:08:27 INFO - {'append_to_log': False, 23:08:27 INFO - 'base_work_dir': '/builds/slave/test', 23:08:27 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 23:08:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:08:27 INFO - 'branch': 'Fx-Team-Non-PGO', 23:08:27 INFO - 'buildbot_json_path': 'buildprops.json', 23:08:27 INFO - 'config_files': ('talos/linux_config.py',), 23:08:27 INFO - 'default_actions': ('clobber', 23:08:27 INFO - 'read-buildbot-config', 23:08:27 INFO - 'download-and-extract', 23:08:27 INFO - 'populate-webroot', 23:08:27 INFO - 'create-virtualenv', 23:08:27 INFO - 'install', 23:08:27 INFO - 'run-tests'), 23:08:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:08:27 INFO - 'download_minidump_stackwalk': True, 23:08:27 INFO - 'download_symbols': 'ondemand', 23:08:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:08:27 INFO - 'tooltool.py': '/tools/tooltool.py', 23:08:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:08:27 INFO - '/tools/misc-python/virtualenv.py')}, 23:08:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:08:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:08:27 INFO - 'installer_path': 'installer.exe', 23:08:27 INFO - 'log_level': 'info', 23:08:27 INFO - 'log_name': 'talos', 23:08:27 INFO - 'log_to_console': True, 23:08:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:08:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:08:27 INFO - 'opt_config_files': (), 23:08:27 INFO - 'pip_index': False, 23:08:27 INFO - 'sps_profile': False, 23:08:27 INFO - 'sps_profile_interval': 0, 23:08:27 INFO - 'suite': 'dromaeojs', 23:08:27 INFO - 'system_bits': '32', 23:08:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:08:27 INFO - 'title': 'talos-linux64-ix-038', 23:08:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:08:27 INFO - 'use_talos_json': True, 23:08:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:08:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:08:27 INFO - 'work_dir': 'build'} 23:08:27 INFO - ##### 23:08:27 INFO - ##### Running clobber step. 23:08:27 INFO - ##### 23:08:27 INFO - Running pre-action listener: _resource_record_pre_action 23:08:27 INFO - Running main action method: clobber 23:08:27 INFO - rmtree: /builds/slave/test/build 23:08:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:08:27 INFO - Running post-action listener: _resource_record_post_action 23:08:27 INFO - ##### 23:08:27 INFO - ##### Finished clobber step (success) 23:08:27 INFO - ##### 23:08:27 INFO - ##### 23:08:27 INFO - ##### Running read-buildbot-config step. 23:08:27 INFO - ##### 23:08:27 INFO - Running pre-action listener: _resource_record_pre_action 23:08:27 INFO - Running main action method: read_buildbot_config 23:08:27 INFO - Using buildbot properties: 23:08:27 INFO - { 23:08:27 INFO - "project": "", 23:08:27 INFO - "product": "firefox", 23:08:27 INFO - "script_repo_revision": "production", 23:08:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:08:27 INFO - "repository": "", 23:08:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 23:08:27 INFO - "buildid": "20160605222532", 23:08:27 INFO - "pgo_build": "False", 23:08:27 INFO - "basedir": "/builds/slave/test", 23:08:27 INFO - "buildnumber": 1821, 23:08:27 INFO - "slavename": "talos-linux64-ix-038", 23:08:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:08:27 INFO - "platform": "ubuntu64_hw", 23:08:27 INFO - "scheduler": "tests-fx-team-linux64-talos", 23:08:27 INFO - "branch": "fx-team", 23:08:27 INFO - "repo_path": "integration/fx-team", 23:08:27 INFO - "revision": "824355c9efe23d83f7e67a4b2c9e0e837f7c7771", 23:08:27 INFO - "stage_platform": "linux64", 23:08:27 INFO - "builduid": "d91ea748e74840eba03f006164e6976d", 23:08:27 INFO - "slavebuilddir": "test" 23:08:27 INFO - } 23:08:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 23:08:27 INFO - Running post-action listener: _resource_record_post_action 23:08:27 INFO - ##### 23:08:27 INFO - ##### Finished read-buildbot-config step (success) 23:08:27 INFO - ##### 23:08:27 INFO - ##### 23:08:27 INFO - ##### Running download-and-extract step. 23:08:27 INFO - ##### 23:08:27 INFO - Running pre-action listener: _resource_record_pre_action 23:08:27 INFO - Running main action method: download_and_extract 23:08:27 INFO - mkdir: /builds/slave/test/build/tests 23:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:27 INFO - https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:08:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:08:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:08:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:08:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:08:29 INFO - Downloaded 1361 bytes. 23:08:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 23:08:29 INFO - Using the following test package requirements: 23:08:29 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:08:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:08:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:08:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:29 INFO - u'jsshell-linux-x86_64.zip'], 23:08:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:08:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 23:08:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:08:29 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:08:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:08:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 23:08:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:08:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:29 INFO - https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:08:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:08:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:08:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:08:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:08:30 INFO - Downloaded 22868152 bytes. 23:08:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:08:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:08:32 INFO - Return code: 0 23:08:32 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:32 INFO - https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:08:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:08:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:08:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 23:08:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:08:33 INFO - Downloaded 22868152 bytes. 23:08:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:08:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:08:34 INFO - Return code: 0 23:08: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')]} 23:08:34 INFO - https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:08:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:08:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:08:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 23:08:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:08:35 INFO - Downloaded 10833369 bytes. 23:08:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:08:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:08:35 INFO - Return code: 0 23:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:35 INFO - https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:08:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:08:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:08:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:08:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:08:37 INFO - Downloaded 55183220 bytes. 23:08:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:08:37 INFO - mkdir: /builds/slave/test/properties 23:08:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:08:37 INFO - Writing to file /builds/slave/test/properties/build_url 23:08:37 INFO - Contents: 23:08:37 INFO - build_url:https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 23:08:38 INFO - Running post-action listener: _resource_record_post_action 23:08:38 INFO - Running post-action listener: set_extra_try_arguments 23:08:38 INFO - ##### 23:08:38 INFO - ##### Finished download-and-extract step (success) 23:08:38 INFO - ##### 23:08:38 INFO - ##### 23:08:38 INFO - ##### Running populate-webroot step. 23:08:38 INFO - ##### 23:08:38 INFO - Running pre-action listener: _resource_record_pre_action 23:08:38 INFO - Running main action method: populate_webroot 23:08:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:08:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:08:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:08:38 INFO - '--tppagecycles', 23:08:38 INFO - '7'], 23:08:38 INFO - 'tests': ['tsvgm']}}, 23:08:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:08:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:08:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:08:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:08:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:08:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:08:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:38 INFO - 'talos_options': ['--disable-e10s'], 23:08:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:08:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:08:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:38 INFO - 'talos_options': ['--disable-e10s'], 23:08:38 INFO - 'tests': ['damp', 'tps']}, 23:08:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:38 INFO - 'tests': ['damp', 'tps']}, 23:08:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:08:38 INFO - 'tests': ['dromaeo_dom']}, 23:08:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:08:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:08:38 INFO - 'tests': ['a11yr', 23:08:38 INFO - 'ts_paint', 23:08:38 INFO - 'tpaint', 23:08:38 INFO - 'sessionrestore', 23:08:38 INFO - 'sessionrestore_no_auto_restore', 23:08:38 INFO - 'tabpaint']}, 23:08:38 INFO - 'other-e10s': {'tests': ['a11yr', 23:08:38 INFO - 'ts_paint', 23:08:38 INFO - 'tpaint', 23:08:38 INFO - 'sessionrestore', 23:08:38 INFO - 'sessionrestore_no_auto_restore', 23:08:38 INFO - 'tabpaint']}, 23:08:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:08:38 INFO - 'ts_paint', 23:08:38 INFO - 'tpaint', 23:08:38 INFO - 'sessionrestore', 23:08:38 INFO - 'sessionrestore_no_auto_restore', 23:08:38 INFO - 'tabpaint']}, 23:08:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:08:38 INFO - 'ts_paint', 23:08:38 INFO - 'tpaint', 23:08:38 INFO - 'sessionrestore', 23:08:38 INFO - 'sessionrestore_no_auto_restore', 23:08:38 INFO - 'tabpaint']}, 23:08:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:08:38 INFO - 'tests': ['a11yr', 23:08:38 INFO - 'ts_paint', 23:08:38 INFO - 'tpaint', 23:08:38 INFO - 'sessionrestore', 23:08:38 INFO - 'sessionrestore_no_auto_restore', 23:08:38 INFO - 'tabpaint']}, 23:08:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:08:38 INFO - 'tests': ['a11yr', 23:08:38 INFO - 'ts_paint', 23:08:38 INFO - 'tpaint', 23:08:38 INFO - 'sessionrestore', 23:08:38 INFO - 'sessionrestore_no_auto_restore', 23:08:38 INFO - 'tabpaint']}, 23:08:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:08:38 INFO - 'tests': ['tsvgx', 23:08:38 INFO - 'tsvgr_opacity', 23:08:38 INFO - 'tart', 23:08:38 INFO - 'tscrollx', 23:08:38 INFO - 'cart']}, 23:08:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:08:38 INFO - 'tsvgr_opacity', 23:08:38 INFO - 'tart', 23:08:38 INFO - 'tscrollx', 23:08:38 INFO - 'cart']}, 23:08:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:38 INFO - 'talos_options': ['--disable-e10s'], 23:08:38 INFO - 'tests': ['tp5o']}, 23:08:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:38 INFO - 'tests': ['tp5o']}, 23:08:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:38 INFO - 'talos_options': ['--disable-e10s', 23:08:38 INFO - '--xperf_path', 23:08:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:08:38 INFO - 'tests': ['tp5n']}, 23:08:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:08:38 INFO - 'talos_options': ['--xperf_path', 23:08:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:08:38 INFO - 'tests': ['tp5n']}}, 23:08:38 INFO - 'talos.zip': {'path': '', 23:08:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:08:38 INFO - Running post-action listener: _resource_record_post_action 23:08:38 INFO - ##### 23:08:38 INFO - ##### Finished populate-webroot step (success) 23:08:38 INFO - ##### 23:08:38 INFO - ##### 23:08:38 INFO - ##### Running create-virtualenv step. 23:08:38 INFO - ##### 23:08:38 INFO - Running pre-action listener: _resource_record_pre_action 23:08:38 INFO - Running main action method: create_virtualenv 23:08:38 INFO - Creating virtualenv /builds/slave/test/build/venv 23:08:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:08:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:08:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:08:38 INFO - Using real prefix '/usr' 23:08:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:08:39 INFO - Installing distribute.............................................................................................................................................................................................done. 23:08:41 INFO - Installing pip.................done. 23:08:41 INFO - Return code: 0 23:08:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:41 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 0x7fb7ec6c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:08:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:08:41 INFO - Using env: {'DISPLAY': ':0', 23:08:41 INFO - 'HOME': '/home/cltbld', 23:08:41 INFO - 'LANG': 'en_US.UTF-8', 23:08:41 INFO - 'LANGUAGE': 'en_US:en', 23:08:41 INFO - 'LOGNAME': 'cltbld', 23:08:41 INFO - 'MAIL': '/var/mail/cltbld', 23:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:41 INFO - 'MOZ_NO_REMOTE': '1', 23:08:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:41 INFO - 'NO_EM_RESTART': '1', 23:08:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:41 INFO - 'PWD': '/builds/slave/test', 23:08:41 INFO - 'SHELL': '/bin/bash', 23:08:41 INFO - 'SHLVL': '1', 23:08:41 INFO - 'TERM': 'linux', 23:08:41 INFO - 'TMOUT': '86400', 23:08:41 INFO - 'USER': 'cltbld', 23:08:41 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:41 INFO - '_': '/tools/buildbot/bin/python'} 23:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:08:41 INFO - Cleaning up... 23:08:41 INFO - Return code: 0 23:08:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:41 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 0x7fb7ec6c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:08:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:08:41 INFO - Using env: {'DISPLAY': ':0', 23:08:41 INFO - 'HOME': '/home/cltbld', 23:08:41 INFO - 'LANG': 'en_US.UTF-8', 23:08:41 INFO - 'LANGUAGE': 'en_US:en', 23:08:41 INFO - 'LOGNAME': 'cltbld', 23:08:41 INFO - 'MAIL': '/var/mail/cltbld', 23:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:41 INFO - 'MOZ_NO_REMOTE': '1', 23:08:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:41 INFO - 'NO_EM_RESTART': '1', 23:08:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:41 INFO - 'PWD': '/builds/slave/test', 23:08:41 INFO - 'SHELL': '/bin/bash', 23:08:41 INFO - 'SHLVL': '1', 23:08:41 INFO - 'TERM': 'linux', 23:08:41 INFO - 'TMOUT': '86400', 23:08:41 INFO - 'USER': 'cltbld', 23:08:41 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:41 INFO - '_': '/tools/buildbot/bin/python'} 23:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:41 INFO - Downloading/unpacking psutil>=3.1.1 23:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:08:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:08:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:08:43 INFO - Installing collected packages: psutil 23:08:43 INFO - Running setup.py install for psutil 23:08:43 INFO - building 'psutil._psutil_linux' extension 23:08:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 23:08:43 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:08:43 INFO - building 'psutil._psutil_posix' extension 23:08:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:08:43 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:08:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:08:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:08:44 INFO - Successfully installed psutil 23:08:44 INFO - Cleaning up... 23:08:44 INFO - Return code: 0 23:08:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7ec6c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:08:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:08:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:08:44 INFO - Using env: {'DISPLAY': ':0', 23:08:44 INFO - 'HOME': '/home/cltbld', 23:08:44 INFO - 'LANG': 'en_US.UTF-8', 23:08:44 INFO - 'LANGUAGE': 'en_US:en', 23:08:44 INFO - 'LOGNAME': 'cltbld', 23:08:44 INFO - 'MAIL': '/var/mail/cltbld', 23:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:44 INFO - 'MOZ_NO_REMOTE': '1', 23:08:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:44 INFO - 'NO_EM_RESTART': '1', 23:08:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:44 INFO - 'PWD': '/builds/slave/test', 23:08:44 INFO - 'SHELL': '/bin/bash', 23:08:44 INFO - 'SHLVL': '1', 23:08:44 INFO - 'TERM': 'linux', 23:08:44 INFO - 'TMOUT': '86400', 23:08:44 INFO - 'USER': 'cltbld', 23:08:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:44 INFO - '_': '/tools/buildbot/bin/python'} 23:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:08:46 INFO - Installing collected packages: mozsystemmonitor 23:08:46 INFO - Running setup.py install for mozsystemmonitor 23:08:46 INFO - Successfully installed mozsystemmonitor 23:08:46 INFO - Cleaning up... 23:08:46 INFO - Return code: 0 23:08:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:46 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 0x7fb7ec6c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:08:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:08:46 INFO - Using env: {'DISPLAY': ':0', 23:08:46 INFO - 'HOME': '/home/cltbld', 23:08:46 INFO - 'LANG': 'en_US.UTF-8', 23:08:46 INFO - 'LANGUAGE': 'en_US:en', 23:08:46 INFO - 'LOGNAME': 'cltbld', 23:08:46 INFO - 'MAIL': '/var/mail/cltbld', 23:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:46 INFO - 'MOZ_NO_REMOTE': '1', 23:08:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:46 INFO - 'NO_EM_RESTART': '1', 23:08:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:46 INFO - 'PWD': '/builds/slave/test', 23:08:46 INFO - 'SHELL': '/bin/bash', 23:08:46 INFO - 'SHLVL': '1', 23:08:46 INFO - 'TERM': 'linux', 23:08:46 INFO - 'TMOUT': '86400', 23:08:46 INFO - 'USER': 'cltbld', 23:08:46 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:46 INFO - '_': '/tools/buildbot/bin/python'} 23:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:46 INFO - Downloading/unpacking jsonschema==2.5.1 23:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:08:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:48 INFO - Downloading functools32-3.2.3-2.tar.gz 23:08:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:08:48 INFO - warning: no files found matching '*.txt' 23:08:48 INFO - no previously-included directories found matching 'build' 23:08:48 INFO - no previously-included directories found matching 'dist' 23:08:48 INFO - no previously-included directories found matching '.git*' 23:08:48 INFO - Installing collected packages: jsonschema, functools32 23:08:48 INFO - Running setup.py install for functools32 23:08:48 INFO - warning: no files found matching '*.txt' 23:08:48 INFO - no previously-included directories found matching 'build' 23:08:48 INFO - no previously-included directories found matching 'dist' 23:08:48 INFO - no previously-included directories found matching '.git*' 23:08:48 INFO - Successfully installed jsonschema functools32 23:08:48 INFO - Cleaning up... 23:08:48 INFO - Return code: 0 23:08:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb7ec6c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:08:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:08:48 INFO - Using env: {'DISPLAY': ':0', 23:08:48 INFO - 'HOME': '/home/cltbld', 23:08:48 INFO - 'LANG': 'en_US.UTF-8', 23:08:48 INFO - 'LANGUAGE': 'en_US:en', 23:08:48 INFO - 'LOGNAME': 'cltbld', 23:08:48 INFO - 'MAIL': '/var/mail/cltbld', 23:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:48 INFO - 'MOZ_NO_REMOTE': '1', 23:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:48 INFO - 'NO_EM_RESTART': '1', 23:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:48 INFO - 'PWD': '/builds/slave/test', 23:08:48 INFO - 'SHELL': '/bin/bash', 23:08:48 INFO - 'SHLVL': '1', 23:08:48 INFO - 'TERM': 'linux', 23:08:48 INFO - 'TMOUT': '86400', 23:08:48 INFO - 'USER': 'cltbld', 23:08:48 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:48 INFO - '_': '/tools/buildbot/bin/python'} 23:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:49 INFO - Downloading/unpacking blobuploader==1.2.4 23:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:51 INFO - Downloading blobuploader-1.2.4.tar.gz 23:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:08:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:08:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:51 INFO - Downloading docopt-0.6.1.tar.gz 23:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:08:51 INFO - Installing collected packages: blobuploader, requests, docopt 23:08:51 INFO - Running setup.py install for blobuploader 23:08:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:08:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:08:51 INFO - Running setup.py install for requests 23:08:52 INFO - Running setup.py install for docopt 23:08:52 INFO - Successfully installed blobuploader requests docopt 23:08:52 INFO - Cleaning up... 23:08:52 INFO - Return code: 0 23:08:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:52 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 0x7fb7ec6c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:08:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:08:52 INFO - Using env: {'DISPLAY': ':0', 23:08:52 INFO - 'HOME': '/home/cltbld', 23:08:52 INFO - 'LANG': 'en_US.UTF-8', 23:08:52 INFO - 'LANGUAGE': 'en_US:en', 23:08:52 INFO - 'LOGNAME': 'cltbld', 23:08:52 INFO - 'MAIL': '/var/mail/cltbld', 23:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:52 INFO - 'MOZ_NO_REMOTE': '1', 23:08:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:52 INFO - 'NO_EM_RESTART': '1', 23:08:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:52 INFO - 'PWD': '/builds/slave/test', 23:08:52 INFO - 'SHELL': '/bin/bash', 23:08:52 INFO - 'SHLVL': '1', 23:08:52 INFO - 'TERM': 'linux', 23:08:52 INFO - 'TMOUT': '86400', 23:08:52 INFO - 'USER': 'cltbld', 23:08:52 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:52 INFO - '_': '/tools/buildbot/bin/python'} 23:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:08:52 INFO - Running setup.py (path:/tmp/pip-kJOrBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:08:52 INFO - Running setup.py (path:/tmp/pip-UxElac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:08:52 INFO - Running setup.py (path:/tmp/pip-7NOygZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:08:52 INFO - Running setup.py (path:/tmp/pip-Uz3o9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:08:52 INFO - Running setup.py (path:/tmp/pip-q7afxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:08:52 INFO - Running setup.py (path:/tmp/pip-J7fZdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:08:53 INFO - Running setup.py (path:/tmp/pip-46pMWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:08:53 INFO - Running setup.py (path:/tmp/pip-Xc1hmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:08:53 INFO - Running setup.py (path:/tmp/pip-Pzj5od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:08:53 INFO - Running setup.py (path:/tmp/pip-bfKoB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:08:53 INFO - Running setup.py (path:/tmp/pip-gc3leg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:08:53 INFO - Running setup.py (path:/tmp/pip-zd9cOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:08:53 INFO - Running setup.py (path:/tmp/pip-e4Ju_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:08:53 INFO - Running setup.py (path:/tmp/pip-9Gb7iY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:08:53 INFO - Running setup.py (path:/tmp/pip-yBRkvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:08:53 INFO - Running setup.py (path:/tmp/pip-ZkfPKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:08:53 INFO - Running setup.py (path:/tmp/pip-fzQRAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:08:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:08:53 INFO - Running setup.py install for manifestparser 23:08:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:08:53 INFO - Running setup.py install for mozcrash 23:08:54 INFO - Running setup.py install for mozdebug 23:08:54 INFO - Running setup.py install for mozdevice 23:08:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:08:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:08:54 INFO - Running setup.py install for mozfile 23:08:54 INFO - Running setup.py install for mozhttpd 23:08:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:08:54 INFO - Running setup.py install for mozinfo 23:08:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:08:54 INFO - Running setup.py install for mozInstall 23:08:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:08:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:08:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:08:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:08:54 INFO - Running setup.py install for mozleak 23:08:54 INFO - Running setup.py install for mozlog 23:08:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:08:54 INFO - Running setup.py install for moznetwork 23:08:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:08:55 INFO - Running setup.py install for mozprocess 23:08:55 INFO - Running setup.py install for mozprofile 23:08:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:08:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:08:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:08:55 INFO - Running setup.py install for mozrunner 23:08:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:08:55 INFO - Running setup.py install for mozscreenshot 23:08:55 INFO - Running setup.py install for moztest 23:08:55 INFO - Running setup.py install for mozversion 23:08:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:08:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:08:55 INFO - Cleaning up... 23:08:55 INFO - Return code: 0 23:08:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:55 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 0x7fb7ec6c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:08:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:08:55 INFO - Using env: {'DISPLAY': ':0', 23:08:55 INFO - 'HOME': '/home/cltbld', 23:08:55 INFO - 'LANG': 'en_US.UTF-8', 23:08:55 INFO - 'LANGUAGE': 'en_US:en', 23:08:55 INFO - 'LOGNAME': 'cltbld', 23:08:55 INFO - 'MAIL': '/var/mail/cltbld', 23:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:55 INFO - 'MOZ_NO_REMOTE': '1', 23:08:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:55 INFO - 'NO_EM_RESTART': '1', 23:08:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:55 INFO - 'PWD': '/builds/slave/test', 23:08:55 INFO - 'SHELL': '/bin/bash', 23:08:55 INFO - 'SHLVL': '1', 23:08:55 INFO - 'TERM': 'linux', 23:08:55 INFO - 'TMOUT': '86400', 23:08:55 INFO - 'USER': 'cltbld', 23:08:55 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:55 INFO - '_': '/tools/buildbot/bin/python'} 23:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:08:55 INFO - Running setup.py (path:/tmp/pip-lREFh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:08:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:08:55 INFO - Running setup.py (path:/tmp/pip-Gjd0cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:08:56 INFO - Running setup.py (path:/tmp/pip-vUoopD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:08:56 INFO - Running setup.py (path:/tmp/pip-KJpcUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:08:56 INFO - Running setup.py (path:/tmp/pip-oCAl_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:08:56 INFO - Running setup.py (path:/tmp/pip-NHxWNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:08:56 INFO - Running setup.py (path:/tmp/pip-nmzRyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:08:56 INFO - Running setup.py (path:/tmp/pip-TddiXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:08:56 INFO - Running setup.py (path:/tmp/pip-OIve5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:08:56 INFO - Running setup.py (path:/tmp/pip-hlk5QJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:08:56 INFO - Running setup.py (path:/tmp/pip-EzCwQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:08:56 INFO - Running setup.py (path:/tmp/pip-ebWbuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:08:56 INFO - Running setup.py (path:/tmp/pip-9cMLrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:08:56 INFO - Running setup.py (path:/tmp/pip-s6dtOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:08:56 INFO - Running setup.py (path:/tmp/pip-pLx5V0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:08:57 INFO - Running setup.py (path:/tmp/pip-A1Nl8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:08:57 INFO - Running setup.py (path:/tmp/pip-YIq840-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:08:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:59 INFO - Downloading blessings-1.6.tar.gz 23:08:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:08:59 INFO - Installing collected packages: blessings 23:08:59 INFO - Running setup.py install for blessings 23:08:59 INFO - Successfully installed blessings 23:08:59 INFO - Cleaning up... 23:08:59 INFO - Return code: 0 23:08:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:08:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:08:59 INFO - Reading from file tmpfile_stdout 23:08:59 INFO - Current package versions: 23:08:59 INFO - argparse == 1.2.1 23:08:59 INFO - blessings == 1.6 23:08:59 INFO - blobuploader == 1.2.4 23:08:59 INFO - docopt == 0.6.1 23:08:59 INFO - functools32 == 3.2.3-2 23:08:59 INFO - jsonschema == 2.5.1 23:08:59 INFO - manifestparser == 1.1 23:08:59 INFO - mozInstall == 1.12 23:08:59 INFO - mozcrash == 0.17 23:08:59 INFO - mozdebug == 0.1 23:08:59 INFO - mozdevice == 0.48 23:08:59 INFO - mozfile == 1.2 23:08:59 INFO - mozhttpd == 0.7 23:08:59 INFO - mozinfo == 0.9 23:08:59 INFO - mozleak == 0.1 23:08:59 INFO - mozlog == 3.2 23:08:59 INFO - moznetwork == 0.27 23:08:59 INFO - mozprocess == 0.22 23:08:59 INFO - mozprofile == 0.28 23:08:59 INFO - mozrunner == 6.11 23:08:59 INFO - mozscreenshot == 0.1 23:08:59 INFO - mozsystemmonitor == 0.3 23:08:59 INFO - moztest == 0.7 23:08:59 INFO - mozversion == 1.4 23:08:59 INFO - psutil == 3.1.1 23:08:59 INFO - requests == 1.2.3 23:08:59 INFO - wsgiref == 0.1.2 23:08:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23: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')]} 23:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23: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')]} 23:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:59 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 0x7fb7ec6c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:08:59 INFO - Using env: {'DISPLAY': ':0', 23:08:59 INFO - 'HOME': '/home/cltbld', 23:08:59 INFO - 'LANG': 'en_US.UTF-8', 23:08:59 INFO - 'LANGUAGE': 'en_US:en', 23:08:59 INFO - 'LOGNAME': 'cltbld', 23:08:59 INFO - 'MAIL': '/var/mail/cltbld', 23:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:59 INFO - 'MOZ_NO_REMOTE': '1', 23:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:59 INFO - 'NO_EM_RESTART': '1', 23:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:59 INFO - 'PWD': '/builds/slave/test', 23:08:59 INFO - 'SHELL': '/bin/bash', 23:08:59 INFO - 'SHLVL': '1', 23:08:59 INFO - 'TERM': 'linux', 23:08:59 INFO - 'TMOUT': '86400', 23:08:59 INFO - 'USER': 'cltbld', 23:08:59 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:59 INFO - '_': '/tools/buildbot/bin/python'} 23:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:08:59 INFO - Cleaning up... 23:08:59 INFO - Return code: 0 23:08:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23: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')]} 23:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23: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')]} 23:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:59 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 0x7fb7ec6c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:08:59 INFO - Using env: {'DISPLAY': ':0', 23:08:59 INFO - 'HOME': '/home/cltbld', 23:08:59 INFO - 'LANG': 'en_US.UTF-8', 23:08:59 INFO - 'LANGUAGE': 'en_US:en', 23:08:59 INFO - 'LOGNAME': 'cltbld', 23:08:59 INFO - 'MAIL': '/var/mail/cltbld', 23:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:59 INFO - 'MOZ_NO_REMOTE': '1', 23:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:59 INFO - 'NO_EM_RESTART': '1', 23:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:59 INFO - 'PWD': '/builds/slave/test', 23:08:59 INFO - 'SHELL': '/bin/bash', 23:08:59 INFO - 'SHLVL': '1', 23:08:59 INFO - 'TERM': 'linux', 23:08:59 INFO - 'TMOUT': '86400', 23:08:59 INFO - 'USER': 'cltbld', 23:08:59 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:59 INFO - '_': '/tools/buildbot/bin/python'} 23:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:08:59 INFO - Cleaning up... 23:08:59 INFO - Return code: 0 23:08:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23: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')]} 23:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23: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')]} 23:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:08:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7ec6c5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:08:59 INFO - Using env: {'DISPLAY': ':0', 23:08:59 INFO - 'HOME': '/home/cltbld', 23:08:59 INFO - 'LANG': 'en_US.UTF-8', 23:08:59 INFO - 'LANGUAGE': 'en_US:en', 23:08:59 INFO - 'LOGNAME': 'cltbld', 23:08:59 INFO - 'MAIL': '/var/mail/cltbld', 23:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:59 INFO - 'MOZ_NO_REMOTE': '1', 23:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:08:59 INFO - 'NO_EM_RESTART': '1', 23:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:08:59 INFO - 'PWD': '/builds/slave/test', 23:08:59 INFO - 'SHELL': '/bin/bash', 23:08:59 INFO - 'SHLVL': '1', 23:08:59 INFO - 'TERM': 'linux', 23:08:59 INFO - 'TMOUT': '86400', 23:08:59 INFO - 'USER': 'cltbld', 23:08:59 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:08:59 INFO - '_': '/tools/buildbot/bin/python'} 23:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:00 INFO - Downloading/unpacking flake8 23:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:09:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:09:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:09:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:09:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:09:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:09:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:09:02 INFO - no previously-included directories found matching 'docs/_build' 23:09:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:09:02 INFO - Running setup.py install for pep8 23:09:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:09:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:09:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:09:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:09:02 INFO - no previously-included directories found matching 'docs/_build' 23:09:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:09:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:09:03 INFO - Cleaning up... 23:09:03 INFO - Return code: 0 23:09:03 INFO - Running post-action listener: _resource_record_post_action 23:09:03 INFO - Running post-action listener: _start_resource_monitoring 23:09:03 INFO - Starting resource monitoring. 23:09:03 INFO - ##### 23:09:03 INFO - ##### Finished create-virtualenv step (success) 23:09:03 INFO - ##### 23:09:03 INFO - ##### 23:09:03 INFO - ##### Running install step. 23:09:03 INFO - ##### 23:09:03 INFO - Running pre-action listener: _resource_record_pre_action 23:09:03 INFO - Running main action method: install 23:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:09:03 INFO - Reading from file tmpfile_stdout 23:09:03 INFO - Detecting whether we're running mozinstall >=1.0... 23:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:09:03 INFO - Reading from file tmpfile_stdout 23:09:03 INFO - Output received: 23:09:03 INFO - Usage: mozinstall [options] installer 23:09:03 INFO - Options: 23:09:03 INFO - -h, --help show this help message and exit 23:09:03 INFO - -d DEST, --destination=DEST 23:09:03 INFO - Directory to install application into. [default: 23:09:03 INFO - "/builds/slave/test"] 23:09:03 INFO - --app=APP Application being installed. [default: firefox] 23:09:03 INFO - mkdir: /builds/slave/test/build/application 23:09:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:09:19 INFO - Reading from file tmpfile_stdout 23:09:19 INFO - Output received: 23:09:19 INFO - /builds/slave/test/build/application/firefox/firefox 23:09:19 INFO - Running post-action listener: _resource_record_post_action 23:09:19 INFO - ##### 23:09:19 INFO - ##### Finished install step (success) 23:09:19 INFO - ##### 23:09:19 INFO - ##### 23:09:19 INFO - ##### Running run-tests step. 23:09:19 INFO - ##### 23:09:19 INFO - Running pre-action listener: _resource_record_pre_action 23:09:19 INFO - Running main action method: run_tests 23:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:09:19 INFO - Python 2.7.3 23:09:19 INFO - Return code: 0 23:09:19 INFO - grabbing minidump binary from tooltool 23:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:09:19 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 0x1cba1c0>, '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 0x1cbb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cbb550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:09:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:09:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 23:09:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:09:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:09:19 INFO - Return code: 0 23:09:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:09:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:09:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:09:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:09:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:09:20 INFO - Return code: 0 23:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:09:20 INFO - Using env: {'DISPLAY': ':0', 23:09:20 INFO - 'HOME': '/home/cltbld', 23:09:20 INFO - 'LANG': 'en_US.UTF-8', 23:09:20 INFO - 'LANGUAGE': 'en_US:en', 23:09:20 INFO - 'LOGNAME': 'cltbld', 23:09:20 INFO - 'MAIL': '/var/mail/cltbld', 23:09:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:09:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:20 INFO - 'MOZ_NO_REMOTE': '1', 23:09:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:09:20 INFO - 'NO_EM_RESTART': '1', 23:09:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:20 INFO - 'PWD': '/builds/slave/test', 23:09:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:09:20 INFO - 'SHELL': '/bin/bash', 23:09:20 INFO - 'SHLVL': '1', 23:09:20 INFO - 'TERM': 'linux', 23:09:20 INFO - 'TMOUT': '86400', 23:09:20 INFO - 'USER': 'cltbld', 23:09:20 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394', 23:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:20 INFO - '_': '/tools/buildbot/bin/python'} 23:09:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:09:20 INFO - mozversion application_buildid: 20160605222532 23:09:20 INFO - mozversion application_changeset: 824355c9efe23d83f7e67a4b2c9e0e837f7c7771 23:09:20 INFO - mozversion application_display_name: Nightly 23:09:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:09:20 INFO - mozversion application_name: Firefox 23:09:20 INFO - mozversion application_remotingname: firefox 23:09:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:09:20 INFO - mozversion application_vendor: Mozilla 23:09:20 INFO - mozversion application_version: 49.0a1 23:09:20 INFO - mozversion platform_buildid: 20160605222532 23:09:20 INFO - mozversion platform_changeset: 824355c9efe23d83f7e67a4b2c9e0e837f7c7771 23:09:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:09:20 INFO - mozversion platform_version: 49.0a1 23:09:20 INFO - using testdate: 1465193360 23:09:20 INFO - actual date: 1465193360 23:09:20 INFO - starting webserver on 'localhost:48078' 23:09:20 INFO - SUITE-START | Running 2 tests 23:09:20 INFO - TEST-START | dromaeo_css 23:09:20 INFO - operating with platform_type : linux_ 23:09:20 INFO - Initialising browser for dromaeo_css test... 23:09:20 INFO - TEST-INFO | started process 18518 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP8f5MF/profile http://localhost:48078/getInfo.html) 23:09:25 INFO - PROCESS | 18518 | __metrics Screen width/height:1600/1200 23:09:25 INFO - PROCESS | 18518 | colorDepth:24 23:09:25 INFO - PROCESS | 18518 | Browser inner width/height: 1024/697 23:09:25 INFO - PROCESS | 18518 | __metrics 23:09:26 ERROR - PROCESS | 18518 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:09:27 INFO - TEST-INFO | 18518: exit 0 23:09:27 INFO - Browser initialized. 23:09:27 INFO - Running cycle 1/1 for dromaeo_css test... 23:09:27 INFO - TEST-INFO | started process 18600 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP8f5MF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:09:39 INFO - PROCESS | 18600 | RSS: Main: 157437952 23:09:39 INFO - PROCESS | 18600 | 23:10:31 INFO - PROCESS | 18600 | 1465193431554 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 23:12:43 INFO - PROCESS | 18600 | Cycle 1(1): loaded http://localhost:48078/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48078/tests/dromaeo/cssquery-ext.html) 23:12:44 INFO - PROCESS | 18600 | RSS: Main: 261320704 23:12:44 INFO - PROCESS | 18600 | 23:15:47 INFO - PROCESS | 18600 | Cycle 1(1): loaded http://localhost:48078/tests/dromaeo/cssquery-ext.html (next: http://localhost:48078/tests/dromaeo/cssquery-jquery.html) 23:15:47 INFO - PROCESS | 18600 | RSS: Main: 245194752 23:15:47 INFO - PROCESS | 18600 | 23:18:50 INFO - PROCESS | 18600 | Cycle 1(1): loaded http://localhost:48078/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48078/tests/dromaeo/cssquery-mootools.html) 23:18:50 INFO - PROCESS | 18600 | RSS: Main: 247242752 23:18:50 INFO - PROCESS | 18600 | 23:21:53 INFO - PROCESS | 18600 | Cycle 1(1): loaded http://localhost:48078/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48078/tests/dromaeo/cssquery-prototype.html) 23:21:53 INFO - PROCESS | 18600 | RSS: Main: 231497728 23:21:53 INFO - PROCESS | 18600 | 23:24:56 INFO - PROCESS | 18600 | Cycle 1(1): loaded http://localhost:48078/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48078/tests/dromaeo/cssquery-yui.html) 23:24:57 INFO - PROCESS | 18600 | RSS: Main: 218001408 23:24:57 INFO - PROCESS | 18600 | 23:28:00 INFO - PROCESS | 18600 | Cycle 1(1): loaded http://localhost:48078/tests/dromaeo/cssquery-yui.html (next: http://localhost:48078/tests/dromaeo/cssquery-dojo.html) 23:28:00 INFO - PROCESS | 18600 | RSS: Main: 280559616 23:28:00 INFO - PROCESS | 18600 | 23:28:00 INFO - PROCESS | 18600 | __start_tp_report 23:28:00 INFO - PROCESS | 18600 | _x_x_mozilla_page_load 23:28:00 INFO - PROCESS | 18600 | _x_x_mozilla_page_load_details 23:28:00 INFO - PROCESS | 18600 | |i|pagename|runs| 23:28:00 INFO - PROCESS | 18600 | |0;dojo.html;2634.84190948543;2663.0679262258213;2665.5868340407233;2675.036986684793;2707.093123803525;4515.638748900088;4604.6776725629215;4685.906281874365;4739.1706451371;4781.306710526976;4691.483936766953;4702.67081304308;4711.505291944348;4713.976430117845;4746.952530474694;4274.422952901357;4347.847825326115;4364.825406983733;4389.31965545341;4541.750203738793;4433.933490997638;4473.373727678128;4476.194285028693;4485.663575231871;4492.101579684077;4242.490901091858;4292.055747735493;4300.3549467580115;4325.394444777734;4337.826486940518;4298.634616057639;4313.072689371768;4321.092570560155;4360.694751367406;4367.061081867414;4334.544872788343;4338.284183109783;4344.6089851913475;4388.626966707834;4391.29739241723;4494.70784399013;4535.206338890681;4581.1753884300815;4595.632349412039;4637.397138372024;5007.874803129915;5040.36995375578;5107.029664363759;5108.003939231851;5111.1566591512355;5102.387713474407;5102.540771330598;5115.155999260118;5115.923261151086;5128.410232823236;4621.4685311189305;4686.789094490688;4741.762911854394;4755.809767609326;4832.178529649968;5148.253503242009;5190.558802501754;5236.973815130977;5240.292560504359;5245.973770131125;4964.553190212898;5022.146235139962;5029.673071250357;5035.144025515671;5041.974790126026;4927.679700819435;4954.653174277766;5004.449510553836;5016.046951079284;5119.0529751996;4971.1549036663855;4990.476000020019;4992.925106123411;4998.850034498971;5031.547160755549;4958.851234462972;4971.701697898137;4973.32860063894;4977.253411988231;5028.874278143003;4142.420061191436;4202.243596152661;4204.285271503852;4247.97876010618;4250.766207858535;3707.462417949382;3725.6460636239512;3745.400735882246;3746.7189960752507;3752.699784017272;4373.2565463871215;4381.671374646914;4388.648908087345;4421.226285400042;4422.535633758409;5103.132467480537;5112.590992720648;5117;5119.923201151985;5169.586433085344;4265.701400901907;4338.674599405057;4376.7811609419405;4383.079553293844;4386.188555117314;4580.702254353523;4593.724376537419;4595.356650068926;4599.425071866017;4648.976755116203;4815.398075240595;4821.156297647968;4822.132016237111;4822.638302127284;4864.683795553277;4931.753412329441;5038.949610503848;5070.974645126751;5092.0579692756955;5116.769745361393;4761.262004389325;4797.784099715591;4800.879978000578;4809.893724443327;4860.149473842134;4832.178529650038;5027.824026159067;5039.899202016012;5042.899142017212;5095.363079615048;5018.021485810378;5034.924476132787;5070.822521211888;5089.618278629044;5099.1331473648825;4929.852104436873;4933.407991041098;4939.6542242042715;4940.407151141886;4990.3762029746285;4820.542048505386;4878.829240976549;4899.828506002273;4923.384576927884;4958.157113290821;4804.591609713214;4900.387451568554;4917.877053073702;4931.211006239128;4944.554990050912;5003.299538064601;5009.223570346602;5011.348524691767;5057.291979123;5063;4668.813247470063;4795.112904112751;4837.951620483891;4882.998985208095;4892.633052521146 23:28:00 INFO - PROCESS | 18600 | |1;ext.html;551.845483264686;590.2150140313387;593.6645795125754;594.6848170469652;611.6146827498677;27059.458810823795;29651.851740741295;29673.554896676567;29696;29705.554416683713;29997.400051999;39928.80035599825;41005.58994410055;41029;45079.32381014287;7645.923540764591;7808.414368922326;7812.257835505647;7818;7820.335271501901;6684.29814869439;7723.304902558769;7857.921420785776;9737.221022318197;10064.748381290454;10028.949855250714;10099.89900100997;10180.64367747129;10187.59249630014;10187.796244075114;9988.1510071644;10092.646757363456;10093.646722364681;10102.949485252526;10105.797884042351;7306.086739157606;7652.808679782995;7653.923460765377;9593.184579310768;10071.69784906454;35449.82275088634;35883.64116358829;35911.28177436437;35927.82036089829;35931;3035.9392812143856;3036.8633411496317;3083.352495975848;3087.8764849405998;3103.7206651401484;27809.860950695318;29231.12306630783;29258.41483170346;29377.412451751057;29536.556951645744;3302.653221411741;3413.2490852012515;3438.896833095011;3441.346144232588;3515.630858759819;244919;246336;246641;247643;247857;50998.74500627547;51072.744636277326;51279.743601281756;51334.74332628313;51348.74325628348;54082;54315.45684543183;54373.45626543763;54502.182467263025;54541.1818822718;12845.293508857103;13075;13146.014048946367;13158.671033224055;13185.011124165534;9327.440353578808;10075.244356673278;10192.796144077078;10215.284930054973;10222.948885255675;14864.770284457418;15467.690646187239;15534.689306213813;15973.920130399274;16009.119498427466;6426.2327859838915;6428.164338636041;6607.504437167232;6629.635370054693;6651.7671881484885;820.4707963363597;821.1542111625035;922.294444749767;931.6133804471068;980.122790102854;25533.3616659585;25913.870430647727;26382.472350552882;26476.735232647814;26561;994.2841154368842;1022.6267412394423;1053.9736506587396;1057.4131357096885;1074.6131392698621;14015;14451.638709032359;14743.410263589336;15704.99391472639;16505;352117;357659;359946;359998;360026;48365.75817120962;48515.272270915964;48515.84646997934;48537.27194092092;48576.75711621419;1131.1911982932243;1204.1330242225758;1211.249025604131;1253.423425224407;1260.174585646403;26691.86654066717;28028;28313;28629.570556442086;28743.85628071846;40079;40479.392809107296;40696.3895541573;40713.38929915994;40780;276897;277151;277330;277455;279731;21819.78180218241;22563.323100307018;23282.417939551648;24415.51168976646;24527.38681532976;39851.80074099611;40856.1828763429;40944;40989.59010409858;41080.58919410768;34595;34776.13059673427;34994.475082873265;35265.47101793526;35588.64411355957;34096.82951585226;34606.30787384188;34654;34729.82635086808;34926.30147396987;33450.33099337951;33730.831345843115;34030.48954265638;34040.82979585086;34301.82849085838;30829.075127746204;31461.213469663442;31461.213469663442;31565.842170788997;31625.36749264956;23021.88489057544;23332;23482.295531134092;23595.41011474727;23661 23:28:00 INFO - PROCESS | 18600 | |2;jquery.html;357.64414407664367;361.2107545014143;366.4613018862273;375.75575875680823;380.9067974912001;15597.688046239082;16769.580760480992;17063.82936170638;17142.40001599943;17338.56653583661;14499.5650130496;15066.698666026701;15144;15158.090514569134;15163.241837908115;4864.12445759764;4986.9251961220425;5123.462036486171;5132.435432102466;5132.769025393866;3679.1537946272238;5002;5568.916466253009;5848.561357898154;5871.442212989759;5908.4091590841;5911.88176236475;5924.940750592504;5929.051351783715;5940.792072277472;5619.297587801525;5785.103308987113;5880.794172203974;5999.340072592011;6004.0693692477735;3343.164208947763;4876.951230487685;5111.1566591512355;5184.455632158644;5198.038362902875;19392.4182274532;20049.79950200494;20050.498737531532;20142;20164.092615832324;1935.3226370770258;1972.2702600037935;2007.979920200794;2024.180207016161;2028.148177765342;12887.871121288854;12919.612411627666;12954.287514186715;13032.674183145402;13425;1691.8731095167911;1699.4986478988728;1724.1896308735;1780.4391616640717;1808.439383791029;60100.39899600992;62546.37453625451;62861.68569157125;62958;64083.35916640867;9042.954785226031;9446.716598502057;9486.667966621135;9710.320277580642;9881.901180988098;12726;12767.808482872764;12804.871951280555;12810.359482025862;12821.166624169398;6646.468282537386;6661.666916654148;6674.866502669919;6736.730530778812;6777.457803375718;6166.445019948226;6228.719707613168;6313.210848643919;6354.3963323484195;6391.58454700443;5832.708364581754;5952.993738162287;5997.2803263607775;6093.019768575231;6126.417990290915;1567.968640627181;1630.9266083026291;1655.5364497940354;1707.846293833561;1715.9914200428918;875.8292133034124;938.7183844846519;938.7559234598917;1001.9229328471922;1006.4162785584343;16077.196140192944;16274.837251627569;16525.173741312887;16952.152392380332;16952.74570881438;889.973300800977;907.9773005674779;909.8089401225817;928.1182876267573;935.6912218967724;9269.90730092704;9692.273079519064;9735.610575577039;9736.172425343784;9908.752281193028;372168;373586;380266;383148;384434;64006.67996659987;64925.35074649288;65355;65599.34400655932;66092.33907660862;958.2573505533268;965.9710208693891;967.086103632075;991.1525645573075;996.3324572536418;14929.477468288558;15114.848851511344;15152.31814568325;15254.618634533781;15381.230938453256;31079.533806992462;31086.68913310838;31449.371012579162;31677.366452670358;31899;42582.787086064374;42727;42785.35821962736;42899.57100428956;43049.569504304556;7384.52000619958;7411.740589079356;7476.252374762697;7561.621918904143;7653.502522336253;11365.147613929154;11607.941960290145;11939.641810745692;11975.64073077809;11992.460339284578;9753.756156096155;9825.705228843146;9891.109800117736;10115.443650599287;10343.689689309333;9946.651867184613;9967.950160249153;9973.30186886911;10035;10249.9487502562;9967.302288839712;10176.573190662086;10283.794324113325;10440.425776582057;10541.841872372073;7435.888461672971;7568.697252109854;7579.1284002340435;7581.734639287599;7639.847203056019;10155.695329140137;10185.592576296865;10217.569540264518;10303.536340864828;10392.545043693737 23:28:00 INFO - PROCESS | 18600 | |3;mootools.html;1275.8086287056935;1383.8962077844164;1415.0165634883756;1522.588900996731;1748.763916871222;6441.549091563593;7301.233370496095;7549.396048316135;7554.622268886561;7752.341051010657;7477.214892436297;7597.696092156307;7634.35108015818;7641.885371719415;7644.273793989576;1828.0676854804078;1885.886846789197;1886.4340697790676;2123.3629911026705;2153.160267495678;1896.0330231581854;2098.821600163988;2175.8368122390807;2177.6298029335053;2177.7060096886876;2066.60734460452;2090.6132365512353;2094.424033390822;2095.339967910111;2115.6614941609346;1800.3698705453119;2102.4113248290428;2119.7456305243313;2119.788021197883;2121.554473560554;2027.807358300959;2081.510844951435;2086.5305306306113;2099.044934554774;2102.6951092091713;7068.964655176743;7154.4634152438775;7154.642267886585;7155.212926578072;7159.498835081495;4232.026633874196;4343.67422443318;4363.040131171138;4371.540988196257;4372.409724687158;2842.4599326128177;2951.601533792932;3008;3044.29981104345;3044.588980487628;829.2785276809154;850.7958090058403;854.3250831842837;864.0063926484593;868.9391742578022;10013;10223.795524089552;10246.7438314042;10267.229957753123;10267.79464410715;4093.324601440729;4111.588841115924;4129.587041295847;4176.728512646669;4206.326987681956;8846.95576522126;8848.46909185451;8880.777980550409;8882.955585222033;8888.022317545065;4364.934525982113;4374.540673229329;4377.802998864995;4379.430674012422;4380.102079073782;3480.878169264114;3541.4333706606817;3937.527496700357;4023.195360927826;4027.597240275949;3475.8957231283102;3558.91102722434;3579.982100089483;3588.3899737044244;3590.1922067534597;1149.3793351590216;1163.03468121459;1168.404113901899;1172.402074941769;1193.8030225012776;714.3820595185127;792.9881051784228;798.253632853284;868.8349213649386;883.2051153961357;5021.297018417425;5281.947180528223;5415.972920135374;5440.836774896759;5487.972560137174;843.4306842881031;854.372036553133;874.6501399440275;904.9411788233743;913.5340976102103;3460.7923524588605;3609.458581212839;3627.6916462101017;3744.6629803317833;3749.718771092179;9114.544272786334;9375;9426.575804088832;9462.479563623929;9472.242220622335;9214.631414743335;9356.017618150132;9363.672271470467;9366.29752768545;9374.85937710935;6725.562838415487;7100.538464999759;7257.491975561766;7261.963690181621;7264.200937896827;5375.5162035417;5457.890842183214;5543.833684989457;5547.5007249347755;5610.382857885711;8546.78633034179;8651.048384677555;8671.262942649946;8739.43193692408;8739.563021848755;8627.784305392415;8820.5589720515;8852;8910.37627366104;8949.239314658327;5972.283326000907;6110.694465276807;6137.232845894263;6204.13142160107;6354.205724284465;7433.851322973618;7452.217517160837;7479.214682458482;7502.849943001001;7527.2849079337375;6575.835604109936;6766.560173588701;6795.1845778506895;6849.657517124024;6907.447404207752;6510.902336464862;6532.183477065367;6627.105340779126;6672.399484046459;6715.56348837324;6243.094751261133;6256.343083976299;6326.177596912372;6338.714757835816;6352.301246862934;4689.109069276827;4692.1084993851055;4732.645051621211;4813.855584332476;4827.951720482891;4721.126591580569;4751.453582838018;4812.735299558418;4821.083994041121;4849.903001940012 23:28:00 INFO - PROCESS | 18600 | |4;prototype.html;285.60158578782597;289.63361347894914;293.61683003680196;296.8337730870713;297.6264787691447;4729.763511824408;4749.786259618317;4763.2378819388905;4765.428148622166;4819.228923372261;4723.220668589679;4723.763811809413;4729.266963620636;4733.195356789345;4739.31279964405;3544.5392099027154;3630.5280313559333;3672.649504317239;3718.9442158367647;3791.4881490998637;3728.1984373359724;3739.6821270192067;3742.981285093571;3750.7374483786148;3766.1902690921447;3533.717302615785;3721.5534135903595;3726.9440958385644;3729;3730.1091653968256;3611.663684436762;3703.3148867459604;3719.20037192005;3723.5717892442176;3724.4599533067826;3268.428025095599;3271.2312606537444;3277.115178901683;3278.852451639682;3283.1463819406886;4556.157110934488;4566.429196350457;4574.6340292776495;4581.839635612738;4587.807170135756;4046.615571520701;4245;4252.659787217046;4257.701960862741;4273.081287523198;4920.483349248313;4960.181570040939;4980.484855148164;4993.177490216081;5006.924896126561;3630.8729194478333;3631.1648320886343;3632.346177688015;3635.1275693833554;3643.6720695137565;4996.7001979881325;5203.843884683428;5214.713190774506;5246.449122842085;5246.7638956247065;3668.79821609814;3691.8523259069875;3705.6479634434686;3713.6472035156617;3731.477593136963;3750.5686846012513;3766.4162054881535;3766.7363284570365;3781.149241420658;3787.905302367463;4261.062566235423;4264.211120942636;4266.530681625019;4271.359296105609;4272.29507131326;4875.561199491992;4988.700677959334;5003.524665156877;5011.674241174312;5014.598832093424;1291.6254286257067;1300.9284489352988;1301.2452777389092;1302.5115581656878;1310.2335133946558;837.9203975622306;839.3453106576758;859.8005781934291;862.8878245828002;864.9524276164746;4304.117655880537;4307.784610769449;4425.380446737459;4457.8439754608435;4463.955360446354;5161.845144645667;5179.404368497596;5195.532402083755;5195.7142357170715;5201.869953251199;4224.66202703783;4410.647148228199;4432.202203603318;4439.556044395535;4467.396901418332;2721.4965231432247;2739.575365818301;2747.738964798341;2756.2833663247297;2764.5991331256855;4793.880152996133;4851.58761505276;4857.392825896763;4874.123462466905;4898.9712160446;5053.797848086109;5292.438730574489;5305.65513241638;5314.362276526842;5316.76074576645;4800.207965685623;4831.444383895757;4866.610671146371;4879.585235254972;4906.632002599819;5220.190870415092;5232.189010703347;5246.790128394821;5249.317588713443;5269;4884.267359896044;5004.39947206323;5036.546710796046;5064.543696307362;5145.742712864267;4847.061175529816;4853.4418541868135;4863.124637565272;4874.878128046686;4879.438864530624;3762.3792074307435;3772.75477093988;3775.9244815102993;3785.5646600640175;3800.1259710267345;4996.800127994839;5134.871628209325;5154.819581314636;5190.6107041971245;5221.190715439113;4981.452040275494;5002.624803139706;5003.274525193754;5006.574441172541;5013;5002.899942001212;5012.423571289349;5020.146575082172;5025.74871256443;5055.494450555062;4661.883452913704;4696.2251228548275;4908.202324674901;4929.580985616263;4933.1880827927735;4941.851744447672;5049.747512624427;5051;5055.6713813602;5063.670861393997;4881.560659540658;4881.585065269492;4886.65793394459;4900.14247506692;4919.434265059564 23:28:00 INFO - PROCESS | 18600 | |5;yui.html;641.9614823110613;671.5869740109831;706.6643344411402;733.2667332667332;740.4668638580224;11775.646730598086;12987.870121298783;13813.30933453327;14013.929930350334;14043.5084772033;14402.49591264306;14405;14447.566573002801;14450;14514.201718905457;639.68015992004;774.7791879314397;803.8673618852882;812.5409143833745;820.1019883227856;797.6849144587884;800.2557621412085;801.7434420985287;802.2138304461631;802.7993001749562;836.2515548584014;837.3887062444419;837.5184269045324;837.6649340263882;837.9329653627725;656.8620589676174;812.175641723651;832.7085520067989;835.2858306148247;837.3259526081501;613.5674349583533;613.6900865063114;613.9079138129272;614.3549273263056;614.6035806904566;12195.756084878341;12198.207116537485;12238.449269782881;12247.142700011003;12252.938735306356;11898.643040708792;12149.939250303692;12297.385130743427;12482.875171248354;12653.114282000264;10253.282270241087;10440.895591044069;11502.07983361329;11727.589534366341;11786.528538858436;3239.2549713565777;3255.1536600484046;3259.364423937333;3368.6294507604143;3371.7976921384798;57940.42059579393;58441.12338314971;58984;58994.41005589932;60352.39647603556;10288.794224115476;10387.896121038844;10487.161027117812;10531.94734026325;10723.03492685662;18552.165152568166;18555.628887422175;18578.721319180222;18580.44258672242;18591.44225673232;6554.934450655528;6618.404343609002;6664.466842652576;6772.322767723188;6781.050652908597;3707.1288247261873;3844.7308688392104;3849.7690138591774;3876.205377897524;3902.570717221103;3254.8860789872715;3493.056874643832;3500.177458297298;3532.0993146747414;3539.911502212414;578.138573525444;582.0309185206668;582.3594046548762;582.8338923406809;611.8990366589463;585.876965837179;619.4827319188438;623.7598524568074;629.2291942370559;639.88162189995;8623.870641940375;8655.956720216485;8689;8796.824063518694;8992.415492992934;590.7991282963727;599.3646734461398;606.2815563557199;610.7190692281512;626.9028300613412;4077.612626800449;4080.4695389599756;4111.280525907954;4140.710150289451;4173.812178451977;60492.39507604956;95517;96544;100315;100433;79560.60219698865;81037.35331910079;81968.18031819725;84262;84657.57671211728;61609.38390616126;74771;75234.48593084433;75635.62182189054;76495.23504764993;7941.841163176588;8046.356291496549;8207.630656620586;8208.753737387888;8237.752867413987;44832.775836120614;45685;46505.30242046304;47166.52833471621;47595.28607070966;61531;65898.67050664716;66057;66468;67180;9801.215902727621;9850.064243896819;10106.646267380607;10268.07587317145;11592.942035290107;12556.309402982924;12575.93712031434;12587.93706031464;12969.221846689228;13098.738025239632;11954.820677689668;12504.336620904918;12672.303023333805;12981.610551683465;13027.609171724484;11984.520619175135;12610.684732881751;12913.806292905801;12922.741545168856;13011.154274972096;11672.649820505398;12409.44157512896;12798.104132710621;13116.737665246832;13155.473781048651;9511.191548718436;9794.412335259907;9883.357581757451;9987.300888937705;10306.433146177033;10232.901516927528;10444.895551044392;10465.738356541147;10514.316569422963;10525.578976840841 23:28:00 INFO - PROCESS | 18600 | __end_tp_report 23:28:00 INFO - PROCESS | 18600 | __start_cc_report 23:28:00 INFO - PROCESS | 18600 | _x_x_mozilla_cycle_collect,958 23:28:00 INFO - PROCESS | 18600 | __end_cc_report 23:28:00 INFO - PROCESS | 18600 | __startTimestamp1465194480660__endTimestamp 23:28:00 INFO - PROCESS | 18600 | 23:28:00 INFO - PROCESS | 18600 | ------- Summary: start ------- 23:28:00 INFO - PROCESS | 18600 | Number of tests: 6 23:28:00 INFO - PROCESS | 18600 | 23:28:00 INFO - PROCESS | 18600 | [#0] dojo.html Cycles:165 Average:4677.48 Median:4820.54 stddev:490.99 (10.2%) stddev-sans-first:465.62 23:28:00 INFO - PROCESS | 18600 | Values: 2634.8 2663.1 2665.6 2675.0 2707.1 4515.6 4604.7 4685.9 4739.2 4781.3 4691.5 4702.7 4711.5 4714.0 4747.0 4274.4 4347.8 4364.8 4389.3 4541.8 4433.9 4473.4 4476.2 4485.7 4492.1 4242.5 4292.1 4300.4 4325.4 4337.8 4298.6 4313.1 4321.1 4360.7 4367.1 4334.5 4338.3 4344.6 4388.6 4391.3 4494.7 4535.2 4581.2 4595.6 4637.4 5007.9 5040.4 5107.0 5108.0 5111.2 5102.4 5102.5 5115.2 5115.9 5128.4 4621.5 4686.8 4741.8 4755.8 4832.2 5148.3 5190.6 5237.0 5240.3 5246.0 4964.6 5022.1 5029.7 5035.1 5042.0 4927.7 4954.7 5004.4 5016.0 5119.1 4971.2 4990.5 4992.9 4998.9 5031.5 4958.9 4971.7 4973.3 4977.3 5028.9 4142.4 4202.2 4204.3 4248.0 4250.8 3707.5 3725.6 3745.4 3746.7 3752.7 4373.3 4381.7 4388.6 4421.2 4422.5 5103.1 5112.6 5117.0 5119.9 5169.6 4265.7 4338.7 4376.8 4383.1 4386.2 4580.7 4593.7 4595.4 4599.4 4649.0 4815.4 4821.2 4822.1 4822.6 4864.7 4931.8 5038.9 5071.0 5092.1 5116.8 4761.3 4797.8 4800.9 4809.9 4860.1 4832.2 5027.8 5039.9 5042.9 5095.4 5018.0 5034.9 5070.8 5089.6 5099.1 4929.9 4933.4 4939.7 4940.4 4990.4 4820.5 4878.8 4899.8 4923.4 4958.2 4804.6 4900.4 4917.9 4931.2 4944.6 5003.3 5009.2 5011.3 5057.3 5063.0 4668.8 4795.1 4838.0 4883.0 4892.6 23:28:00 INFO - PROCESS | 18600 | 23:28:00 INFO - PROCESS | 18600 | [#1] ext.html Cycles:180 Average:44512.78 Median:18376.49 stddev:78199.76 (425.5%) stddev-sans-first:78349.47 23:28:00 INFO - PROCESS | 18600 | Values: 551.8 590.2 593.7 594.7 611.6 27059.5 29651.9 29673.6 29696.0 29705.6 29997.4 39928.8 41005.6 41029.0 45079.3 7645.9 7808.4 7812.3 7818.0 7820.3 6684.3 7723.3 7857.9 9737.2 10064.7 10028.9 10099.9 10180.6 10187.6 10187.8 9988.2 10092.6 10093.6 10102.9 10105.8 7306.1 7652.8 7653.9 9593.2 10071.7 35449.8 35883.6 35911.3 35927.8 35931.0 3035.9 3036.9 3083.4 3087.9 3103.7 27809.9 29231.1 29258.4 29377.4 29536.6 3302.7 3413.2 3438.9 3441.3 3515.6 244919.0 246336.0 246641.0 247643.0 247857.0 50998.7 51072.7 51279.7 51334.7 51348.7 54082.0 54315.5 54373.5 54502.2 54541.2 12845.3 13075.0 13146.0 13158.7 13185.0 9327.4 10075.2 10192.8 10215.3 10222.9 14864.8 15467.7 15534.7 15973.9 16009.1 6426.2 6428.2 6607.5 6629.6 6651.8 820.5 821.2 922.3 931.6 980.1 25533.4 25913.9 26382.5 26476.7 26561.0 994.3 1022.6 1054.0 1057.4 1074.6 14015.0 14451.6 14743.4 15705.0 16505.0 352117.0 357659.0 359946.0 359998.0 360026.0 48365.8 48515.3 48515.8 48537.3 48576.8 1131.2 1204.1 1211.2 1253.4 1260.2 26691.9 28028.0 28313.0 28629.6 28743.9 40079.0 40479.4 40696.4 40713.4 40780.0 276897.0 277151.0 277330.0 277455.0 279731.0 21819.8 22563.3 23282.4 24415.5 24527.4 39851.8 40856.2 40944.0 40989.6 41080.6 34595.0 34776.1 34994.5 35265.5 35588.6 34096.8 34606.3 34654.0 34729.8 34926.3 33450.3 33730.8 34030.5 34040.8 34301.8 30829.1 31461.2 31461.2 31565.8 31625.4 23021.9 23332.0 23482.3 23595.4 23661.0 23:28:00 INFO - PROCESS | 18600 | 23:28:00 INFO - PROCESS | 18600 | [#2] jquery.html Cycles:180 Average:23080.62 Median:42974.57 stddev:62115.27 (144.5%) stddev-sans-first:62266.09 23:28:00 INFO - PROCESS | 18600 | Values: 357.6 361.2 366.5 375.8 380.9 15597.7 16769.6 17063.8 17142.4 17338.6 14499.6 15066.7 15144.0 15158.1 15163.2 4864.1 4986.9 5123.5 5132.4 5132.8 3679.2 5002.0 5568.9 5848.6 5871.4 5908.4 5911.9 5924.9 5929.1 5940.8 5619.3 5785.1 5880.8 5999.3 6004.1 3343.2 4877.0 5111.2 5184.5 5198.0 19392.4 20049.8 20050.5 20142.0 20164.1 1935.3 1972.3 2008.0 2024.2 2028.1 12887.9 12919.6 12954.3 13032.7 13425.0 1691.9 1699.5 1724.2 1780.4 1808.4 60100.4 62546.4 62861.7 62958.0 64083.4 9043.0 9446.7 9486.7 9710.3 9881.9 12726.0 12767.8 12804.9 12810.4 12821.2 6646.5 6661.7 6674.9 6736.7 6777.5 6166.4 6228.7 6313.2 6354.4 6391.6 5832.7 5953.0 5997.3 6093.0 6126.4 1568.0 1630.9 1655.5 1707.8 1716.0 875.8 938.7 938.8 1001.9 1006.4 16077.2 16274.8 16525.2 16952.2 16952.7 890.0 908.0 909.8 928.1 935.7 9269.9 9692.3 9735.6 9736.2 9908.8 372168.0 373586.0 380266.0 383148.0 384434.0 64006.7 64925.4 65355.0 65599.3 66092.3 958.3 966.0 967.1 991.2 996.3 14929.5 15114.8 15152.3 15254.6 15381.2 31079.5 31086.7 31449.4 31677.4 31899.0 42582.8 42727.0 42785.4 42899.6 43049.6 7384.5 7411.7 7476.3 7561.6 7653.5 11365.1 11607.9 11939.6 11975.6 11992.5 9753.8 9825.7 9891.1 10115.4 10343.7 9946.7 9968.0 9973.3 10035.0 10249.9 9967.3 10176.6 10283.8 10440.4 10541.8 7435.9 7568.7 7579.1 7581.7 7639.8 10155.7 10185.6 10217.6 10303.5 10392.5 23:28:00 INFO - PROCESS | 18600 | 23:28:00 INFO - PROCESS | 18600 | [#3] mootools.html Cycles:180 Average:5013.53 Median:5472.93 stddev:2788.35 (50.9%) stddev-sans-first:2782.02 23:28:00 INFO - PROCESS | 18600 | Values: 1275.8 1383.9 1415.0 1522.6 1748.8 6441.5 7301.2 7549.4 7554.6 7752.3 7477.2 7597.7 7634.4 7641.9 7644.3 1828.1 1885.9 1886.4 2123.4 2153.2 1896.0 2098.8 2175.8 2177.6 2177.7 2066.6 2090.6 2094.4 2095.3 2115.7 1800.4 2102.4 2119.7 2119.8 2121.6 2027.8 2081.5 2086.5 2099.0 2102.7 7069.0 7154.5 7154.6 7155.2 7159.5 4232.0 4343.7 4363.0 4371.5 4372.4 2842.5 2951.6 3008.0 3044.3 3044.6 829.3 850.8 854.3 864.0 868.9 10013.0 10223.8 10246.7 10267.2 10267.8 4093.3 4111.6 4129.6 4176.7 4206.3 8847.0 8848.5 8880.8 8883.0 8888.0 4364.9 4374.5 4377.8 4379.4 4380.1 3480.9 3541.4 3937.5 4023.2 4027.6 3475.9 3558.9 3580.0 3588.4 3590.2 1149.4 1163.0 1168.4 1172.4 1193.8 714.4 793.0 798.3 868.8 883.2 5021.3 5281.9 5416.0 5440.8 5488.0 843.4 854.4 874.7 904.9 913.5 3460.8 3609.5 3627.7 3744.7 3749.7 9114.5 9375.0 9426.6 9462.5 9472.2 9214.6 9356.0 9363.7 9366.3 9374.9 6725.6 7100.5 7257.5 7262.0 7264.2 5375.5 5457.9 5543.8 5547.5 5610.4 8546.8 8651.0 8671.3 8739.4 8739.6 8627.8 8820.6 8852.0 8910.4 8949.2 5972.3 6110.7 6137.2 6204.1 6354.2 7433.9 7452.2 7479.2 7502.8 7527.3 6575.8 6766.6 6795.2 6849.7 6907.4 6510.9 6532.2 6627.1 6672.4 6715.6 6243.1 6256.3 6326.2 6338.7 6352.3 4689.1 4692.1 4732.6 4813.9 4828.0 4721.1 4751.5 4812.7 4821.1 4849.9 23:28:00 INFO - PROCESS | 18600 | 23:28:00 INFO - PROCESS | 18600 | [#4] prototype.html Cycles:180 Average:4162.05 Median:4731.48 stddev:1202.09 (25.4%) stddev-sans-first:1169.72 23:28:00 INFO - PROCESS | 18600 | Values: 285.6 289.6 293.6 296.8 297.6 4729.8 4749.8 4763.2 4765.4 4819.2 4723.2 4723.8 4729.3 4733.2 4739.3 3544.5 3630.5 3672.6 3718.9 3791.5 3728.2 3739.7 3743.0 3750.7 3766.2 3533.7 3721.6 3726.9 3729.0 3730.1 3611.7 3703.3 3719.2 3723.6 3724.5 3268.4 3271.2 3277.1 3278.9 3283.1 4556.2 4566.4 4574.6 4581.8 4587.8 4046.6 4245.0 4252.7 4257.7 4273.1 4920.5 4960.2 4980.5 4993.2 5006.9 3630.9 3631.2 3632.3 3635.1 3643.7 4996.7 5203.8 5214.7 5246.4 5246.8 3668.8 3691.9 3705.6 3713.6 3731.5 3750.6 3766.4 3766.7 3781.1 3787.9 4261.1 4264.2 4266.5 4271.4 4272.3 4875.6 4988.7 5003.5 5011.7 5014.6 1291.6 1300.9 1301.2 1302.5 1310.2 837.9 839.3 859.8 862.9 865.0 4304.1 4307.8 4425.4 4457.8 4464.0 5161.8 5179.4 5195.5 5195.7 5201.9 4224.7 4410.6 4432.2 4439.6 4467.4 2721.5 2739.6 2747.7 2756.3 2764.6 4793.9 4851.6 4857.4 4874.1 4899.0 5053.8 5292.4 5305.7 5314.4 5316.8 4800.2 4831.4 4866.6 4879.6 4906.6 5220.2 5232.2 5246.8 5249.3 5269.0 4884.3 5004.4 5036.5 5064.5 5145.7 4847.1 4853.4 4863.1 4874.9 4879.4 3762.4 3772.8 3775.9 3785.6 3800.1 4996.8 5134.9 5154.8 5190.6 5221.2 4981.5 5002.6 5003.3 5006.6 5013.0 5002.9 5012.4 5020.1 5025.7 5055.5 4661.9 4696.2 4908.2 4929.6 4933.2 4941.9 5049.7 5051.0 5055.7 5063.7 4881.6 4881.6 4886.7 4900.1 4919.4 23:28:00 INFO - PROCESS | 18600 | 23:28:00 INFO - PROCESS | 18600 | [#5] yui.html Cycles:180 Average:17635.83 Median:24086.71 stddev:24639.66 (102.3%) stddev-sans-first:24675.73 23:28:00 INFO - PROCESS | 18600 | Values: 642.0 671.6 706.7 733.3 740.5 11775.6 12987.9 13813.3 14013.9 14043.5 14402.5 14405.0 14447.6 14450.0 14514.2 639.7 774.8 803.9 812.5 820.1 797.7 800.3 801.7 802.2 802.8 836.3 837.4 837.5 837.7 837.9 656.9 812.2 832.7 835.3 837.3 613.6 613.7 613.9 614.4 614.6 12195.8 12198.2 12238.4 12247.1 12252.9 11898.6 12149.9 12297.4 12482.9 12653.1 10253.3 10440.9 11502.1 11727.6 11786.5 3239.3 3255.2 3259.4 3368.6 3371.8 57940.4 58441.1 58984.0 58994.4 60352.4 10288.8 10387.9 10487.2 10531.9 10723.0 18552.2 18555.6 18578.7 18580.4 18591.4 6554.9 6618.4 6664.5 6772.3 6781.1 3707.1 3844.7 3849.8 3876.2 3902.6 3254.9 3493.1 3500.2 3532.1 3539.9 578.1 582.0 582.4 582.8 611.9 585.9 619.5 623.8 629.2 639.9 8623.9 8656.0 8689.0 8796.8 8992.4 590.8 599.4 606.3 610.7 626.9 4077.6 4080.5 4111.3 4140.7 4173.8 60492.4 95517.0 96544.0 100315.0 100433.0 79560.6 81037.4 81968.2 84262.0 84657.6 61609.4 74771.0 75234.5 75635.6 76495.2 7941.8 8046.4 8207.6 8208.8 8237.8 44832.8 45685.0 46505.3 47166.5 47595.3 61531.0 65898.7 66057.0 66468.0 67180.0 9801.2 9850.1 10106.6 10268.1 11592.9 12556.3 12575.9 12587.9 12969.2 13098.7 11954.8 12504.3 12672.3 12981.6 13027.6 11984.5 12610.7 12913.8 12922.7 13011.2 11672.6 12409.4 12798.1 13116.7 13155.5 9511.2 9794.4 9883.4 9987.3 10306.4 10232.9 10444.9 10465.7 10514.3 10525.6 23:28:00 INFO - PROCESS | 18600 | -------- Summary: end -------- 23:28:00 INFO - PROCESS | 18600 | 23:28:01 INFO - TEST-INFO | 18600: exit 0 23:28:01 INFO - TEST-OK | dromaeo_css | took 1120787ms 23:28:01 INFO - TEST-START | kraken 23:28:01 INFO - operating with platform_type : linux_ 23:28:01 INFO - Initialising browser for kraken test... 23:28:01 INFO - TEST-INFO | started process 18780 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5uuenF/profile http://localhost:48078/getInfo.html) 23:28:06 INFO - PROCESS | 18780 | __metrics Screen width/height:1600/1200 23:28:06 INFO - PROCESS | 18780 | colorDepth:24 23:28:06 INFO - PROCESS | 18780 | Browser inner width/height: 1024/697 23:28:06 INFO - PROCESS | 18780 | __metrics 23:28:07 ERROR - PROCESS | 18780 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:28:08 INFO - TEST-INFO | 18780: exit 0 23:28:08 INFO - Browser initialized. 23:28:08 INFO - Running cycle 1/1 for kraken test... 23:28:08 INFO - TEST-INFO | started process 18862 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5uuenF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:28:20 INFO - PROCESS | 18862 | RSS: Main: 157396992 23:28:20 INFO - PROCESS | 18862 | 23:28:23 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48078/tests/kraken/driver.html?testName=audio-beat-detection) 23:28:23 INFO - PROCESS | 18862 | RSS: Main: 214364160 23:28:23 INFO - PROCESS | 18862 | 23:28:26 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48078/tests/kraken/driver.html?testName=audio-dft) 23:28:27 INFO - PROCESS | 18862 | RSS: Main: 201523200 23:28:27 INFO - PROCESS | 18862 | 23:28:30 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48078/tests/kraken/driver.html?testName=audio-fft) 23:28:31 INFO - PROCESS | 18862 | RSS: Main: 269787136 23:28:31 INFO - PROCESS | 18862 | 23:28:33 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48078/tests/kraken/driver.html?testName=audio-oscillator) 23:28:34 INFO - PROCESS | 18862 | RSS: Main: 180162560 23:28:34 INFO - PROCESS | 18862 | 23:28:36 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48078/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:28:36 INFO - PROCESS | 18862 | RSS: Main: 184664064 23:28:36 INFO - PROCESS | 18862 | 23:28:40 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48078/tests/kraken/driver.html?testName=imaging-darkroom) 23:28:40 INFO - PROCESS | 18862 | RSS: Main: 220536832 23:28:40 INFO - PROCESS | 18862 | 23:28:44 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48078/tests/kraken/driver.html?testName=imaging-desaturate) 23:28:44 INFO - PROCESS | 18862 | RSS: Main: 220880896 23:28:44 INFO - PROCESS | 18862 | 23:28:47 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48078/tests/kraken/driver.html?testName=json-parse-financial) 23:28:48 INFO - PROCESS | 18862 | RSS: Main: 221261824 23:28:48 INFO - PROCESS | 18862 | 23:28:50 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48078/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:28:50 INFO - PROCESS | 18862 | RSS: Main: 204079104 23:28:50 INFO - PROCESS | 18862 | 23:28:52 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48078/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:28:53 INFO - PROCESS | 18862 | RSS: Main: 210345984 23:28:53 INFO - PROCESS | 18862 | 23:28:55 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48078/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:28:56 INFO - PROCESS | 18862 | RSS: Main: 218537984 23:28:56 INFO - PROCESS | 18862 | 23:28:58 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48078/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:28:59 INFO - PROCESS | 18862 | RSS: Main: 224997376 23:28:59 INFO - PROCESS | 18862 | 23:29:02 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48078/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:29:02 INFO - PROCESS | 18862 | RSS: Main: 225136640 23:29:02 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | Cycle 1(1): loaded http://localhost:48078/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48078/tests/kraken/driver.html?testName=ai-astar) 23:29:04 INFO - PROCESS | 18862 | RSS: Main: 266829824 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | __start_tp_report 23:29:04 INFO - PROCESS | 18862 | _x_x_mozilla_page_load 23:29:04 INFO - PROCESS | 18862 | _x_x_mozilla_page_load_details 23:29:04 INFO - PROCESS | 18862 | |i|pagename|runs| 23:29:04 INFO - PROCESS | 18862 | |0;ai-astar;112;108;113;112;113;114;111;111;114;112 23:29:04 INFO - PROCESS | 18862 | |1;audio-beat-detection;162;132;131;205;130;131;158;129;185;136 23:29:04 INFO - PROCESS | 18862 | |2;audio-dft;162;162;162;163;162;161;163;160;162;161 23:29:04 INFO - PROCESS | 18862 | |3;audio-fft;86;77;75;115;75;78;76;75;98;76 23:29:04 INFO - PROCESS | 18862 | |4;audio-oscillator;88;86;114;88;87;87;122;86;87;121 23:29:04 INFO - PROCESS | 18862 | |5;imaging-gaussian-blur;116;117;117;117;116;116;117;117;117;118 23:29:04 INFO - PROCESS | 18862 | |6;imaging-darkroom;146;146;148;146;147;146;146;146;145;146 23:29:04 INFO - PROCESS | 18862 | |7;imaging-desaturate;95;94;95;94;94;94;95;94;95;95 23:29:04 INFO - PROCESS | 18862 | |8;json-parse-financial;64;64;65;64;65;65;64;63;65;64 23:29:04 INFO - PROCESS | 18862 | |9;json-stringify-tinderbox;65;65;65;65;65;65;65;65;65;65 23:29:04 INFO - PROCESS | 18862 | |10;stanford-crypto-aes;85;84;91;87;86;86;86;85;121;85 23:29:04 INFO - PROCESS | 18862 | |11;stanford-crypto-ccm;138;139;129;130;128;130;130;132;147;134 23:29:04 INFO - PROCESS | 18862 | |12;stanford-crypto-pbkdf2;171;171;166;164;175;166;167;164;170;170 23:29:04 INFO - PROCESS | 18862 | |13;stanford-crypto-sha256-iterative;68;66;67;67;65;66;67;65;67;66 23:29:04 INFO - PROCESS | 18862 | __end_tp_report 23:29:04 INFO - PROCESS | 18862 | __start_cc_report 23:29:04 INFO - PROCESS | 18862 | _x_x_mozilla_cycle_collect,1885 23:29:04 INFO - PROCESS | 18862 | __end_cc_report 23:29:04 INFO - PROCESS | 18862 | __startTimestamp1465194544933__endTimestamp 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | ------- Summary: start ------- 23:29:04 INFO - PROCESS | 18862 | Number of tests: 14 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.50 stddev:1.76 (1.6%) stddev-sans-first:1.87 23:29:04 INFO - PROCESS | 18862 | Values: 112.0 108.0 113.0 112.0 113.0 114.0 111.0 111.0 114.0 112.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#1] audio-beat-detection Cycles:10 Average:149.90 Median:147.00 stddev:26.96 (18.3%) stddev-sans-first:28.24 23:29:04 INFO - PROCESS | 18862 | Values: 162.0 132.0 131.0 205.0 130.0 131.0 158.0 129.0 185.0 136.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 23:29:04 INFO - PROCESS | 18862 | Values: 162.0 162.0 162.0 163.0 162.0 161.0 163.0 160.0 162.0 161.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#3] audio-fft Cycles:10 Average:83.10 Median:76.50 stddev:13.37 (17.5%) stddev-sans-first:14.14 23:29:04 INFO - PROCESS | 18862 | Values: 86.0 77.0 75.0 115.0 75.0 78.0 76.0 75.0 98.0 76.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:87.00 stddev:15.61 (17.9%) stddev-sans-first:16.24 23:29:04 INFO - PROCESS | 18862 | Values: 88.0 86.0 114.0 88.0 87.0 87.0 122.0 86.0 87.0 121.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 23:29:04 INFO - PROCESS | 18862 | Values: 116.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 118.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 23:29:04 INFO - PROCESS | 18862 | Values: 146.0 146.0 148.0 146.0 147.0 146.0 146.0 146.0 145.0 146.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 23:29:04 INFO - PROCESS | 18862 | Values: 95.0 94.0 95.0 94.0 94.0 94.0 95.0 94.0 95.0 95.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 23:29:04 INFO - PROCESS | 18862 | Values: 64.0 64.0 65.0 64.0 65.0 65.0 64.0 63.0 65.0 64.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 23:29:04 INFO - PROCESS | 18862 | Values: 65.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:86.00 stddev:11.20 (13.0%) stddev-sans-first:11.75 23:29:04 INFO - PROCESS | 18862 | Values: 85.0 84.0 91.0 87.0 86.0 86.0 86.0 85.0 121.0 85.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#11] stanford-crypto-ccm Cycles:10 Average:133.70 Median:133.00 stddev:5.98 (4.5%) stddev-sans-first:6.14 23:29:04 INFO - PROCESS | 18862 | Values: 138.0 139.0 129.0 130.0 128.0 130.0 130.0 132.0 147.0 134.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:170.00 stddev:3.57 (2.1%) stddev-sans-first:3.66 23:29:04 INFO - PROCESS | 18862 | Values: 171.0 171.0 166.0 164.0 175.0 166.0 167.0 164.0 170.0 170.0 23:29:04 INFO - PROCESS | 18862 | 23:29:04 INFO - PROCESS | 18862 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:0.97 (1.4%) stddev-sans-first:0.83 23:29:04 INFO - PROCESS | 18862 | Values: 68.0 66.0 67.0 67.0 65.0 66.0 67.0 65.0 67.0 66.0 23:29:04 INFO - PROCESS | 18862 | -------- Summary: end -------- 23:29:04 INFO - PROCESS | 18862 | 23:29:06 INFO - TEST-INFO | 18862: exit 0 23:29:06 INFO - TEST-OK | kraken | took 64609ms 23:29:06 INFO - SUITE-END | took 1185s 23:29:06 INFO - Completed test suite (00:19:45) 23:29:06 INFO - javascript benchmark 23:29:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2634.84190948543, 2663.0679262258213, 2665.5868340407233, 2675.036986684793, 2707.093123803525, 4515.638748900088, 4604.6776725629215, 4685.906281874365, 4739.1706451371, 4781.306710526976, 4691.483936766953, 4702.67081304308, 4711.505291944348, 4713.976430117845, 4746.952530474694, 4274.422952901357, 4347.847825326115, 4364.825406983733, 4389.31965545341, 4541.750203738793, 4433.933490997638, 4473.373727678128, 4476.194285028693, 4485.663575231871, 4492.101579684077, 4242.490901091858, 4292.055747735493, 4300.3549467580115, 4325.394444777734, 4337.826486940518, 4298.634616057639, 4313.072689371768, 4321.092570560155, 4360.694751367406, 4367.061081867414, 4334.544872788343, 4338.284183109783, 4344.6089851913475, 4388.626966707834, 4391.29739241723, 4494.70784399013, 4535.206338890681, 4581.1753884300815, 4595.632349412039, 4637.397138372024, 5007.874803129915, 5040.36995375578, 5107.029664363759, 5108.003939231851, 5111.1566591512355, 5102.387713474407, 5102.540771330598, 5115.155999260118, 5115.923261151086, 5128.410232823236, 4621.4685311189305, 4686.789094490688, 4741.762911854394, 4755.809767609326, 4832.178529649968, 5148.253503242009, 5190.558802501754, 5236.973815130977, 5240.292560504359, 5245.973770131125, 4964.553190212898, 5022.146235139962, 5029.673071250357, 5035.144025515671, 5041.974790126026, 4927.679700819435, 4954.653174277766, 5004.449510553836, 5016.046951079284, 5119.0529751996, 4971.1549036663855, 4990.476000020019, 4992.925106123411, 4998.850034498971, 5031.547160755549, 4958.851234462972, 4971.701697898137, 4973.32860063894, 4977.253411988231, 5028.874278143003, 4142.420061191436, 4202.243596152661, 4204.285271503852, 4247.97876010618, 4250.766207858535, 3707.462417949382, 3725.6460636239512, 3745.400735882246, 3746.7189960752507, 3752.699784017272, 4373.2565463871215, 4381.671374646914, 4388.648908087345, 4421.226285400042, 4422.535633758409, 5103.132467480537, 5112.590992720648, 5117.0, 5119.923201151985, 5169.586433085344, 4265.701400901907, 4338.674599405057, 4376.7811609419405, 4383.079553293844, 4386.188555117314, 4580.702254353523, 4593.724376537419, 4595.356650068926, 4599.425071866017, 4648.976755116203, 4815.398075240595, 4821.156297647968, 4822.132016237111, 4822.638302127284, 4864.683795553277, 4931.753412329441, 5038.949610503848, 5070.974645126751, 5092.0579692756955, 5116.769745361393, 4761.262004389325, 4797.784099715591, 4800.879978000578, 4809.893724443327, 4860.149473842134, 4832.178529650038, 5027.824026159067, 5039.899202016012, 5042.899142017212, 5095.363079615048, 5018.021485810378, 5034.924476132787, 5070.822521211888, 5089.618278629044, 5099.1331473648825, 4929.852104436873, 4933.407991041098, 4939.6542242042715, 4940.407151141886, 4990.3762029746285, 4820.542048505386, 4878.829240976549, 4899.828506002273, 4923.384576927884, 4958.157113290821, 4804.591609713214, 4900.387451568554, 4917.877053073702, 4931.211006239128, 4944.554990050912, 5003.299538064601, 5009.223570346602, 5011.348524691767, 5057.291979123, 5063.0, 4668.813247470063, 4795.112904112751, 4837.951620483891, 4882.998985208095, 4892.633052521146], "value": 4646.323514013231, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.845483264686, 590.2150140313387, 593.6645795125754, 594.6848170469652, 611.6146827498677, 27059.458810823795, 29651.851740741295, 29673.554896676567, 29696.0, 29705.554416683713, 29997.400051999, 39928.80035599825, 41005.58994410055, 41029.0, 45079.32381014287, 7645.923540764591, 7808.414368922326, 7812.257835505647, 7818.0, 7820.335271501901, 6684.29814869439, 7723.304902558769, 7857.921420785776, 9737.221022318197, 10064.748381290454, 10028.949855250714, 10099.89900100997, 10180.64367747129, 10187.59249630014, 10187.796244075114, 9988.1510071644, 10092.646757363456, 10093.646722364681, 10102.949485252526, 10105.797884042351, 7306.086739157606, 7652.808679782995, 7653.923460765377, 9593.184579310768, 10071.69784906454, 35449.82275088634, 35883.64116358829, 35911.28177436437, 35927.82036089829, 35931.0, 3035.9392812143856, 3036.8633411496317, 3083.352495975848, 3087.8764849405998, 3103.7206651401484, 27809.860950695318, 29231.12306630783, 29258.41483170346, 29377.412451751057, 29536.556951645744, 3302.653221411741, 3413.2490852012515, 3438.896833095011, 3441.346144232588, 3515.630858759819, 244919.0, 246336.0, 246641.0, 247643.0, 247857.0, 50998.74500627547, 51072.744636277326, 51279.743601281756, 51334.74332628313, 51348.74325628348, 54082.0, 54315.45684543183, 54373.45626543763, 54502.182467263025, 54541.1818822718, 12845.293508857103, 13075.0, 13146.014048946367, 13158.671033224055, 13185.011124165534, 9327.440353578808, 10075.244356673278, 10192.796144077078, 10215.284930054973, 10222.948885255675, 14864.770284457418, 15467.690646187239, 15534.689306213813, 15973.920130399274, 16009.119498427466, 6426.2327859838915, 6428.164338636041, 6607.504437167232, 6629.635370054693, 6651.7671881484885, 820.4707963363597, 821.1542111625035, 922.294444749767, 931.6133804471068, 980.122790102854, 25533.3616659585, 25913.870430647727, 26382.472350552882, 26476.735232647814, 26561.0, 994.2841154368842, 1022.6267412394423, 1053.9736506587396, 1057.4131357096885, 1074.6131392698621, 14015.0, 14451.638709032359, 14743.410263589336, 15704.99391472639, 16505.0, 352117.0, 357659.0, 359946.0, 359998.0, 360026.0, 48365.75817120962, 48515.272270915964, 48515.84646997934, 48537.27194092092, 48576.75711621419, 1131.1911982932243, 1204.1330242225758, 1211.249025604131, 1253.423425224407, 1260.174585646403, 26691.86654066717, 28028.0, 28313.0, 28629.570556442086, 28743.85628071846, 40079.0, 40479.392809107296, 40696.3895541573, 40713.38929915994, 40780.0, 276897.0, 277151.0, 277330.0, 277455.0, 279731.0, 21819.78180218241, 22563.323100307018, 23282.417939551648, 24415.51168976646, 24527.38681532976, 39851.80074099611, 40856.1828763429, 40944.0, 40989.59010409858, 41080.58919410768, 34595.0, 34776.13059673427, 34994.475082873265, 35265.47101793526, 35588.64411355957, 34096.82951585226, 34606.30787384188, 34654.0, 34729.82635086808, 34926.30147396987, 33450.33099337951, 33730.831345843115, 34030.48954265638, 34040.82979585086, 34301.82849085838, 30829.075127746204, 31461.213469663442, 31461.213469663442, 31565.842170788997, 31625.36749264956, 23021.88489057544, 23332.0, 23482.295531134092, 23595.41011474727, 23661.0], "value": 17148.356558161253, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.64414407664367, 361.2107545014143, 366.4613018862273, 375.75575875680823, 380.9067974912001, 15597.688046239082, 16769.580760480992, 17063.82936170638, 17142.40001599943, 17338.56653583661, 14499.5650130496, 15066.698666026701, 15144.0, 15158.090514569134, 15163.241837908115, 4864.12445759764, 4986.9251961220425, 5123.462036486171, 5132.435432102466, 5132.769025393866, 3679.1537946272238, 5002.0, 5568.916466253009, 5848.561357898154, 5871.442212989759, 5908.4091590841, 5911.88176236475, 5924.940750592504, 5929.051351783715, 5940.792072277472, 5619.297587801525, 5785.103308987113, 5880.794172203974, 5999.340072592011, 6004.0693692477735, 3343.164208947763, 4876.951230487685, 5111.1566591512355, 5184.455632158644, 5198.038362902875, 19392.4182274532, 20049.79950200494, 20050.498737531532, 20142.0, 20164.092615832324, 1935.3226370770258, 1972.2702600037935, 2007.979920200794, 2024.180207016161, 2028.148177765342, 12887.871121288854, 12919.612411627666, 12954.287514186715, 13032.674183145402, 13425.0, 1691.8731095167911, 1699.4986478988728, 1724.1896308735, 1780.4391616640717, 1808.439383791029, 60100.39899600992, 62546.37453625451, 62861.68569157125, 62958.0, 64083.35916640867, 9042.954785226031, 9446.716598502057, 9486.667966621135, 9710.320277580642, 9881.901180988098, 12726.0, 12767.808482872764, 12804.871951280555, 12810.359482025862, 12821.166624169398, 6646.468282537386, 6661.666916654148, 6674.866502669919, 6736.730530778812, 6777.457803375718, 6166.445019948226, 6228.719707613168, 6313.210848643919, 6354.3963323484195, 6391.58454700443, 5832.708364581754, 5952.993738162287, 5997.2803263607775, 6093.019768575231, 6126.417990290915, 1567.968640627181, 1630.9266083026291, 1655.5364497940354, 1707.846293833561, 1715.9914200428918, 875.8292133034124, 938.7183844846519, 938.7559234598917, 1001.9229328471922, 1006.4162785584343, 16077.196140192944, 16274.837251627569, 16525.173741312887, 16952.152392380332, 16952.74570881438, 889.973300800977, 907.9773005674779, 909.8089401225817, 928.1182876267573, 935.6912218967724, 9269.90730092704, 9692.273079519064, 9735.610575577039, 9736.172425343784, 9908.752281193028, 372168.0, 373586.0, 380266.0, 383148.0, 384434.0, 64006.67996659987, 64925.35074649288, 65355.0, 65599.34400655932, 66092.33907660862, 958.2573505533268, 965.9710208693891, 967.086103632075, 991.1525645573075, 996.3324572536418, 14929.477468288558, 15114.848851511344, 15152.31814568325, 15254.618634533781, 15381.230938453256, 31079.533806992462, 31086.68913310838, 31449.371012579162, 31677.366452670358, 31899.0, 42582.787086064374, 42727.0, 42785.35821962736, 42899.57100428956, 43049.569504304556, 7384.52000619958, 7411.740589079356, 7476.252374762697, 7561.621918904143, 7653.502522336253, 11365.147613929154, 11607.941960290145, 11939.641810745692, 11975.64073077809, 11992.460339284578, 9753.756156096155, 9825.705228843146, 9891.109800117736, 10115.443650599287, 10343.689689309333, 9946.651867184613, 9967.950160249153, 9973.30186886911, 10035.0, 10249.9487502562, 9967.302288839712, 10176.573190662086, 10283.794324113325, 10440.425776582057, 10541.841872372073, 7435.888461672971, 7568.697252109854, 7579.1284002340435, 7581.734639287599, 7639.847203056019, 10155.695329140137, 10185.592576296865, 10217.569540264518, 10303.536340864828, 10392.545043693737], "value": 8122.120247451197, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1275.8086287056935, 1383.8962077844164, 1415.0165634883756, 1522.588900996731, 1748.763916871222, 6441.549091563593, 7301.233370496095, 7549.396048316135, 7554.622268886561, 7752.341051010657, 7477.214892436297, 7597.696092156307, 7634.35108015818, 7641.885371719415, 7644.273793989576, 1828.0676854804078, 1885.886846789197, 1886.4340697790676, 2123.3629911026705, 2153.160267495678, 1896.0330231581854, 2098.821600163988, 2175.8368122390807, 2177.6298029335053, 2177.7060096886876, 2066.60734460452, 2090.6132365512353, 2094.424033390822, 2095.339967910111, 2115.6614941609346, 1800.3698705453119, 2102.4113248290428, 2119.7456305243313, 2119.788021197883, 2121.554473560554, 2027.807358300959, 2081.510844951435, 2086.5305306306113, 2099.044934554774, 2102.6951092091713, 7068.964655176743, 7154.4634152438775, 7154.642267886585, 7155.212926578072, 7159.498835081495, 4232.026633874196, 4343.67422443318, 4363.040131171138, 4371.540988196257, 4372.409724687158, 2842.4599326128177, 2951.601533792932, 3008.0, 3044.29981104345, 3044.588980487628, 829.2785276809154, 850.7958090058403, 854.3250831842837, 864.0063926484593, 868.9391742578022, 10013.0, 10223.795524089552, 10246.7438314042, 10267.229957753123, 10267.79464410715, 4093.324601440729, 4111.588841115924, 4129.587041295847, 4176.728512646669, 4206.326987681956, 8846.95576522126, 8848.46909185451, 8880.777980550409, 8882.955585222033, 8888.022317545065, 4364.934525982113, 4374.540673229329, 4377.802998864995, 4379.430674012422, 4380.102079073782, 3480.878169264114, 3541.4333706606817, 3937.527496700357, 4023.195360927826, 4027.597240275949, 3475.8957231283102, 3558.91102722434, 3579.982100089483, 3588.3899737044244, 3590.1922067534597, 1149.3793351590216, 1163.03468121459, 1168.404113901899, 1172.402074941769, 1193.8030225012776, 714.3820595185127, 792.9881051784228, 798.253632853284, 868.8349213649386, 883.2051153961357, 5021.297018417425, 5281.947180528223, 5415.972920135374, 5440.836774896759, 5487.972560137174, 843.4306842881031, 854.372036553133, 874.6501399440275, 904.9411788233743, 913.5340976102103, 3460.7923524588605, 3609.458581212839, 3627.6916462101017, 3744.6629803317833, 3749.718771092179, 9114.544272786334, 9375.0, 9426.575804088832, 9462.479563623929, 9472.242220622335, 9214.631414743335, 9356.017618150132, 9363.672271470467, 9366.29752768545, 9374.85937710935, 6725.562838415487, 7100.538464999759, 7257.491975561766, 7261.963690181621, 7264.200937896827, 5375.5162035417, 5457.890842183214, 5543.833684989457, 5547.5007249347755, 5610.382857885711, 8546.78633034179, 8651.048384677555, 8671.262942649946, 8739.43193692408, 8739.563021848755, 8627.784305392415, 8820.5589720515, 8852.0, 8910.37627366104, 8949.239314658327, 5972.283326000907, 6110.694465276807, 6137.232845894263, 6204.13142160107, 6354.205724284465, 7433.851322973618, 7452.217517160837, 7479.214682458482, 7502.849943001001, 7527.2849079337375, 6575.835604109936, 6766.560173588701, 6795.1845778506895, 6849.657517124024, 6907.447404207752, 6510.902336464862, 6532.183477065367, 6627.105340779126, 6672.399484046459, 6715.56348837324, 6243.094751261133, 6256.343083976299, 6326.177596912372, 6338.714757835816, 6352.301246862934, 4689.109069276827, 4692.1084993851055, 4732.645051621211, 4813.855584332476, 4827.951720482891, 4721.126591580569, 4751.453582838018, 4812.735299558418, 4821.083994041121, 4849.903001940012], "value": 4038.835841319229, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.60158578782597, 289.63361347894914, 293.61683003680196, 296.8337730870713, 297.6264787691447, 4729.763511824408, 4749.786259618317, 4763.2378819388905, 4765.428148622166, 4819.228923372261, 4723.220668589679, 4723.763811809413, 4729.266963620636, 4733.195356789345, 4739.31279964405, 3544.5392099027154, 3630.5280313559333, 3672.649504317239, 3718.9442158367647, 3791.4881490998637, 3728.1984373359724, 3739.6821270192067, 3742.981285093571, 3750.7374483786148, 3766.1902690921447, 3533.717302615785, 3721.5534135903595, 3726.9440958385644, 3729.0, 3730.1091653968256, 3611.663684436762, 3703.3148867459604, 3719.20037192005, 3723.5717892442176, 3724.4599533067826, 3268.428025095599, 3271.2312606537444, 3277.115178901683, 3278.852451639682, 3283.1463819406886, 4556.157110934488, 4566.429196350457, 4574.6340292776495, 4581.839635612738, 4587.807170135756, 4046.615571520701, 4245.0, 4252.659787217046, 4257.701960862741, 4273.081287523198, 4920.483349248313, 4960.181570040939, 4980.484855148164, 4993.177490216081, 5006.924896126561, 3630.8729194478333, 3631.1648320886343, 3632.346177688015, 3635.1275693833554, 3643.6720695137565, 4996.7001979881325, 5203.843884683428, 5214.713190774506, 5246.449122842085, 5246.7638956247065, 3668.79821609814, 3691.8523259069875, 3705.6479634434686, 3713.6472035156617, 3731.477593136963, 3750.5686846012513, 3766.4162054881535, 3766.7363284570365, 3781.149241420658, 3787.905302367463, 4261.062566235423, 4264.211120942636, 4266.530681625019, 4271.359296105609, 4272.29507131326, 4875.561199491992, 4988.700677959334, 5003.524665156877, 5011.674241174312, 5014.598832093424, 1291.6254286257067, 1300.9284489352988, 1301.2452777389092, 1302.5115581656878, 1310.2335133946558, 837.9203975622306, 839.3453106576758, 859.8005781934291, 862.8878245828002, 864.9524276164746, 4304.117655880537, 4307.784610769449, 4425.380446737459, 4457.8439754608435, 4463.955360446354, 5161.845144645667, 5179.404368497596, 5195.532402083755, 5195.7142357170715, 5201.869953251199, 4224.66202703783, 4410.647148228199, 4432.202203603318, 4439.556044395535, 4467.396901418332, 2721.4965231432247, 2739.575365818301, 2747.738964798341, 2756.2833663247297, 2764.5991331256855, 4793.880152996133, 4851.58761505276, 4857.392825896763, 4874.123462466905, 4898.9712160446, 5053.797848086109, 5292.438730574489, 5305.65513241638, 5314.362276526842, 5316.76074576645, 4800.207965685623, 4831.444383895757, 4866.610671146371, 4879.585235254972, 4906.632002599819, 5220.190870415092, 5232.189010703347, 5246.790128394821, 5249.317588713443, 5269.0, 4884.267359896044, 5004.39947206323, 5036.546710796046, 5064.543696307362, 5145.742712864267, 4847.061175529816, 4853.4418541868135, 4863.124637565272, 4874.878128046686, 4879.438864530624, 3762.3792074307435, 3772.75477093988, 3775.9244815102993, 3785.5646600640175, 3800.1259710267345, 4996.800127994839, 5134.871628209325, 5154.819581314636, 5190.6107041971245, 5221.190715439113, 4981.452040275494, 5002.624803139706, 5003.274525193754, 5006.574441172541, 5013.0, 5002.899942001212, 5012.423571289349, 5020.146575082172, 5025.74871256443, 5055.494450555062, 4661.883452913704, 4696.2251228548275, 4908.202324674901, 4929.580985616263, 4933.1880827927735, 4941.851744447672, 5049.747512624427, 5051.0, 5055.6713813602, 5063.670861393997, 4881.560659540658, 4881.585065269492, 4886.65793394459, 4900.14247506692, 4919.434265059564], "value": 3779.3089317092667, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.9614823110613, 671.5869740109831, 706.6643344411402, 733.2667332667332, 740.4668638580224, 11775.646730598086, 12987.870121298783, 13813.30933453327, 14013.929930350334, 14043.5084772033, 14402.49591264306, 14405.0, 14447.566573002801, 14450.0, 14514.201718905457, 639.68015992004, 774.7791879314397, 803.8673618852882, 812.5409143833745, 820.1019883227856, 797.6849144587884, 800.2557621412085, 801.7434420985287, 802.2138304461631, 802.7993001749562, 836.2515548584014, 837.3887062444419, 837.5184269045324, 837.6649340263882, 837.9329653627725, 656.8620589676174, 812.175641723651, 832.7085520067989, 835.2858306148247, 837.3259526081501, 613.5674349583533, 613.6900865063114, 613.9079138129272, 614.3549273263056, 614.6035806904566, 12195.756084878341, 12198.207116537485, 12238.449269782881, 12247.142700011003, 12252.938735306356, 11898.643040708792, 12149.939250303692, 12297.385130743427, 12482.875171248354, 12653.114282000264, 10253.282270241087, 10440.895591044069, 11502.07983361329, 11727.589534366341, 11786.528538858436, 3239.2549713565777, 3255.1536600484046, 3259.364423937333, 3368.6294507604143, 3371.7976921384798, 57940.42059579393, 58441.12338314971, 58984.0, 58994.41005589932, 60352.39647603556, 10288.794224115476, 10387.896121038844, 10487.161027117812, 10531.94734026325, 10723.03492685662, 18552.165152568166, 18555.628887422175, 18578.721319180222, 18580.44258672242, 18591.44225673232, 6554.934450655528, 6618.404343609002, 6664.466842652576, 6772.322767723188, 6781.050652908597, 3707.1288247261873, 3844.7308688392104, 3849.7690138591774, 3876.205377897524, 3902.570717221103, 3254.8860789872715, 3493.056874643832, 3500.177458297298, 3532.0993146747414, 3539.911502212414, 578.138573525444, 582.0309185206668, 582.3594046548762, 582.8338923406809, 611.8990366589463, 585.876965837179, 619.4827319188438, 623.7598524568074, 629.2291942370559, 639.88162189995, 8623.870641940375, 8655.956720216485, 8689.0, 8796.824063518694, 8992.415492992934, 590.7991282963727, 599.3646734461398, 606.2815563557199, 610.7190692281512, 626.9028300613412, 4077.612626800449, 4080.4695389599756, 4111.280525907954, 4140.710150289451, 4173.812178451977, 60492.39507604956, 95517.0, 96544.0, 100315.0, 100433.0, 79560.60219698865, 81037.35331910079, 81968.18031819725, 84262.0, 84657.57671211728, 61609.38390616126, 74771.0, 75234.48593084433, 75635.62182189054, 76495.23504764993, 7941.841163176588, 8046.356291496549, 8207.630656620586, 8208.753737387888, 8237.752867413987, 44832.775836120614, 45685.0, 46505.30242046304, 47166.52833471621, 47595.28607070966, 61531.0, 65898.67050664716, 66057.0, 66468.0, 67180.0, 9801.215902727621, 9850.064243896819, 10106.646267380607, 10268.07587317145, 11592.942035290107, 12556.309402982924, 12575.93712031434, 12587.93706031464, 12969.221846689228, 13098.738025239632, 11954.820677689668, 12504.336620904918, 12672.303023333805, 12981.610551683465, 13027.609171724484, 11984.520619175135, 12610.684732881751, 12913.806292905801, 12922.741545168856, 13011.154274972096, 11672.649820505398, 12409.44157512896, 12798.104132710621, 13116.737665246832, 13155.473781048651, 9511.191548718436, 9794.412335259907, 9883.357581757451, 9987.300888937705, 10306.433146177033, 10232.901516927528, 10444.895551044392, 10465.738356541147, 10514.316569422963, 10525.578976840841], "value": 6705.851526211292, "unit": "score"}], "name": "dromaeo_css", "value": 6361.052082793735, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 113.0, 112.0, 113.0, 114.0, 111.0, 111.0, 114.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 132.0, 131.0, 205.0, 130.0, 131.0, 158.0, 129.0, 185.0, 136.0], "value": 149.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 163.0, 162.0, 161.0, 163.0, 160.0, 162.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 77.0, 75.0, 115.0, 75.0, 78.0, 76.0, 75.0, 98.0, 76.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 114.0, 88.0, 87.0, 87.0, 122.0, 86.0, 87.0, 121.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 118.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 148.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 65.0, 65.0, 64.0, 63.0, 65.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 91.0, 87.0, 86.0, 86.0, 86.0, 85.0, 121.0, 85.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 129.0, 130.0, 128.0, 130.0, 130.0, 132.0, 147.0, 134.0], "value": 133.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 166.0, 164.0, 175.0, 166.0, 167.0, 164.0, 170.0, 170.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 67.0, 65.0, 66.0, 67.0, 65.0, 67.0, 66.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1548.3, "alertThreshold": 2.0}]} 23:29:06 INFO - Return code: 0 23:29:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:29:06 ERROR - # TBPL SUCCESS # 23:29:06 INFO - Running post-action listener: _resource_record_post_action 23:29:06 INFO - ##### 23:29:06 INFO - ##### Finished run-tests step (success) 23:29:06 INFO - ##### 23:29:06 INFO - Running post-run listener: _resource_record_post_run 23:29:06 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 24576; Write bytes: 539648000; Read time: 184; Write time: 1190340 23:29:06 INFO - TinderboxPrint: CPU usage
13.2% 23:29:06 INFO - TinderboxPrint: I/O read bytes / time
24,576 / 184 23:29:06 INFO - TinderboxPrint: I/O write bytes / time
539,648,000 / 1,190,340 23:29:06 INFO - TinderboxPrint: CPU idle
8,333.9 (86.8%) 23:29:06 INFO - TinderboxPrint: CPU user
1,214.1 (12.6%) 23:29:06 INFO - TinderboxPrint: Swap in / out
0 / 0 23:29:06 INFO - install - Wall time: 16s; CPU: 17.0%; Read bytes: 4096; Write bytes: 245088256; Read time: 124; Write time: 622632 23:29:06 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 20480; Write bytes: 293974016; Read time: 60; Write time: 567504 23:29:06 INFO - Running post-run listener: _upload_blobber_files 23:29:06 INFO - Blob upload gear active. 23:29:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:29:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:29:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:29:07 INFO - (blobuploader) - INFO - Open directory for files ... 23:29:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:29:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:29:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:07 INFO - (blobuploader) - INFO - Done attempting. 23:29:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:29:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:11 INFO - (blobuploader) - INFO - Done attempting. 23:29:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:29:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:29:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:12 INFO - (blobuploader) - INFO - Done attempting. 23:29:12 INFO - (blobuploader) - INFO - Iteration through files over. 23:29:12 INFO - Return code: 0 23:29:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:29:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:29:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e93b2c1872faaaff73165c1d3e8f056be43e91387ddee5344a7176cdc7f41d7c8fb892790513027aca7a4b4786ab5a6e673cc00501e0c7eff161fc83d6dafe09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9cad50314bcb30cffc1e385cbd6d21dc08feacb0004b77a474c18ecca5d957bd9e5ba72770e37c2c6f7bcdebb73e0319ca8d5158cbefd44e7484eef473a0e3b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8f296f70cfe2f3956c3377a597da57a31bcfa375c558c397026c39b3dbe212ef0f040df89b9e698feb58fd5c9e8cabbcbb1e8da74bddc94c116f029f14906e4c"} 23:29:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:29:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:29:12 INFO - Contents: 23:29:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e93b2c1872faaaff73165c1d3e8f056be43e91387ddee5344a7176cdc7f41d7c8fb892790513027aca7a4b4786ab5a6e673cc00501e0c7eff161fc83d6dafe09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9cad50314bcb30cffc1e385cbd6d21dc08feacb0004b77a474c18ecca5d957bd9e5ba72770e37c2c6f7bcdebb73e0319ca8d5158cbefd44e7484eef473a0e3b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8f296f70cfe2f3956c3377a597da57a31bcfa375c558c397026c39b3dbe212ef0f040df89b9e698feb58fd5c9e8cabbcbb1e8da74bddc94c116f029f14906e4c"} 23:29:12 INFO - Running post-run listener: copy_logs_to_upload_dir 23:29:12 INFO - Copying logs to upload dir... 23:29:12 INFO - mkdir: /builds/slave/test/build/upload/logs 23:29:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.503368 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-06-05 23:29:11.887264) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 23:29:11.890983) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394 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-Non-PGO/sha512/e93b2c1872faaaff73165c1d3e8f056be43e91387ddee5344a7176cdc7f41d7c8fb892790513027aca7a4b4786ab5a6e673cc00501e0c7eff161fc83d6dafe09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9cad50314bcb30cffc1e385cbd6d21dc08feacb0004b77a474c18ecca5d957bd9e5ba72770e37c2c6f7bcdebb73e0319ca8d5158cbefd44e7484eef473a0e3b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8f296f70cfe2f3956c3377a597da57a31bcfa375c558c397026c39b3dbe212ef0f040df89b9e698feb58fd5c9e8cabbcbb1e8da74bddc94c116f029f14906e4c"} build_url:https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011349 build_url: 'https://queue.taskcluster.net/v1/task/R6Ysy68PTkG3ZqIWUMsjFw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e93b2c1872faaaff73165c1d3e8f056be43e91387ddee5344a7176cdc7f41d7c8fb892790513027aca7a4b4786ab5a6e673cc00501e0c7eff161fc83d6dafe09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9cad50314bcb30cffc1e385cbd6d21dc08feacb0004b77a474c18ecca5d957bd9e5ba72770e37c2c6f7bcdebb73e0319ca8d5158cbefd44e7484eef473a0e3b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8f296f70cfe2f3956c3377a597da57a31bcfa375c558c397026c39b3dbe212ef0f040df89b9e698feb58fd5c9e8cabbcbb1e8da74bddc94c116f029f14906e4c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 23:29:11.916391) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 23:29:11.916735) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1465192940.613253-2068115394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004508 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 23:29:12.003037) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 23:29:12.003450) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 23:29:12.003807) ========= ========= Total master_lag: 0.17 =========