builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0147 starttime: 1462006297.74 results: success (0) buildid: 20160430005741 builduid: 55fdce966b1e4ee9bbc86eabca4f4d13 revision: aea9eee926979ec130fa3a1a32bdcede88b82a35 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-30 01:51:37.742576) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-30 01:51:37.749605) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-30 01:51:37.749878) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-30 01:51:38.126749) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-30 01:51:38.127024) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-30 01:51:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.4M=0.001s 2016-04-30 01:51:38 (15.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159119 ========= master_lag: 0.96 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-30 01:51:39.249819) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 01:51:39.250092) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.025734 ========= master_lag: 0.97 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 01:51:40.241086) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-30 01:51:40.246469) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev aea9eee926979ec130fa3a1a32bdcede88b82a35 --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 aea9eee926979ec130fa3a1a32bdcede88b82a35 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-30 01:51:40,345 truncating revision to first 12 chars 2016-04-30 01:51:40,346 Setting DEBUG logging. 2016-04-30 01:51:40,346 attempt 1/10 2016-04-30 01:51:40,346 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/aea9eee92697?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-30 01:51:41,698 unpacking tar archive at: fx-team-aea9eee92697/testing/mozharness/ program finished with exit code 0 elapsedTime=1.600629 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-30 01:51:42.115979) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-30 01:51:42.116325) ========= script_repo_revision: aea9eee926979ec130fa3a1a32bdcede88b82a35 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-30 01:51:42.116742) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-30 01:51:42.117012) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-30 01:51:42.811612) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 46 secs) (at 2016-04-30 01:51:42.811893) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 01:51:42 INFO - MultiFileLogger online at 20160430 01:51:42 in /builds/slave/test 01:51:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:51:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:51:42 INFO - {'append_to_log': False, 01:51:42 INFO - 'base_work_dir': '/builds/slave/test', 01:51:42 INFO - 'blob_upload_branch': 'Fx-Team', 01:51:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:51:42 INFO - 'branch': 'Fx-Team', 01:51:42 INFO - 'buildbot_json_path': 'buildprops.json', 01:51:42 INFO - 'config_files': ('talos/mac_config.py',), 01:51:42 INFO - 'default_actions': ('clobber', 01:51:42 INFO - 'read-buildbot-config', 01:51:42 INFO - 'download-and-extract', 01:51:42 INFO - 'populate-webroot', 01:51:42 INFO - 'create-virtualenv', 01:51:42 INFO - 'install', 01:51:42 INFO - 'run-tests'), 01:51:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:51:42 INFO - 'download_minidump_stackwalk': True, 01:51:43 INFO - 'download_symbols': 'ondemand', 01:51:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:51:43 INFO - 'tooltool.py': '/tools/tooltool.py', 01:51:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:51:43 INFO - '/tools/misc-python/virtualenv.py')}, 01:51:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:51:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:51:43 INFO - 'installer_path': 'installer.exe', 01:51:43 INFO - 'log_level': 'info', 01:51:43 INFO - 'log_name': 'talos', 01:51:43 INFO - 'log_to_console': True, 01:51:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:51:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:51:43 INFO - 'opt_config_files': (), 01:51:43 INFO - 'pip_index': False, 01:51:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:51:43 INFO - 'cmd': ('bash', 01:51:43 INFO - '-c', 01:51:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:51:43 INFO - 'enabled': True, 01:51:43 INFO - 'halt_on_failure': False, 01:51:43 INFO - 'name': 'check_screen_resolution'},), 01:51:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:51:43 INFO - 'cmd': ('bash', 01:51:43 INFO - '-c', 01:51:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:51:43 INFO - 'enabled': True, 01:51:43 INFO - 'halt_on_failure': False, 01:51:43 INFO - 'name': 'check_screen_resolution'},), 01:51:43 INFO - 'run_cmd_checks_enabled': True, 01:51:43 INFO - 'sps_profile': False, 01:51:43 INFO - 'sps_profile_interval': 0, 01:51:43 INFO - 'suite': 'dromaeojs', 01:51:43 INFO - 'system_bits': '32', 01:51:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:51:43 INFO - 'title': 't-yosemite-r7-0147', 01:51:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:51:43 INFO - 'use_talos_json': True, 01:51:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:51:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:51:43 INFO - 'work_dir': 'build'} 01:51:43 INFO - ##### 01:51:43 INFO - ##### Running clobber step. 01:51:43 INFO - ##### 01:51:43 INFO - Running pre-action listener: _resource_record_pre_action 01:51:43 INFO - Running main action method: clobber 01:51:43 INFO - rmtree: /builds/slave/test/build 01:51:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:51:45 INFO - Running post-action listener: _resource_record_post_action 01:51:45 INFO - ##### 01:51:45 INFO - ##### Running read-buildbot-config step. 01:51:45 INFO - ##### 01:51:45 INFO - Running pre-action listener: _resource_record_pre_action 01:51:45 INFO - Running main action method: read_buildbot_config 01:51:45 INFO - Using buildbot properties: 01:51:45 INFO - { 01:51:45 INFO - "project": "", 01:51:45 INFO - "product": "firefox", 01:51:45 INFO - "script_repo_revision": "production", 01:51:45 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 01:51:45 INFO - "repository": "", 01:51:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 01:51:45 INFO - "buildid": "20160430005741", 01:51:45 INFO - "pgo_build": "False", 01:51:45 INFO - "basedir": "/builds/slave/test", 01:51:45 INFO - "buildnumber": 801, 01:51:45 INFO - "slavename": "t-yosemite-r7-0147", 01:51:45 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 01:51:45 INFO - "platform": "yosemite_r7", 01:51:45 INFO - "scheduler": "tests-fx-team-macosx64-talos", 01:51:45 INFO - "branch": "fx-team", 01:51:45 INFO - "repo_path": "integration/fx-team", 01:51:45 INFO - "revision": "aea9eee926979ec130fa3a1a32bdcede88b82a35", 01:51:45 INFO - "stage_platform": "macosx64", 01:51:45 INFO - "builduid": "55fdce966b1e4ee9bbc86eabca4f4d13", 01:51:45 INFO - "slavebuilddir": "test" 01:51:45 INFO - } 01:51:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 01:51:45 INFO - Running post-action listener: _resource_record_post_action 01:51:45 INFO - ##### 01:51:45 INFO - ##### Running download-and-extract step. 01:51:45 INFO - ##### 01:51:45 INFO - Running pre-action listener: _resource_record_pre_action 01:51:45 INFO - Running main action method: download_and_extract 01:51:45 INFO - mkdir: /builds/slave/test/build/tests 01:51:45 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')]} 01:51:45 INFO - https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:51:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:51:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:51:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:51:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 01:52:12 INFO - Downloaded 1199 bytes. 01:52:12 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:52:12 INFO - Using the following test package requirements: 01:52:12 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:52:12 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:52:12 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 01:52:12 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:52:12 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 01:52:12 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:52:12 INFO - u'jsshell-mac.zip'], 01:52:12 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:52:12 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 01:52:12 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:52:12 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:52:12 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 01:52:12 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:52:12 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 01:52:12 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:52:12 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 01:52:12 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:52:12 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 01:52:12 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:12 INFO - https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:52:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:52:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:52:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:52:14 INFO - Downloaded 18127817 bytes. 01:52:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:52:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:52:17 INFO - Return code: 0 01:52:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:17 INFO - https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:52:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:52:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:52:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:52:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:52:18 INFO - Downloaded 18127817 bytes. 01:52:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:52:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:52:22 INFO - Return code: 0 01:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:22 INFO - https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:52:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:52:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:52:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:52:23 INFO - Downloaded 10840152 bytes. 01:52:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:52:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:52:23 INFO - Return code: 0 01:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:23 INFO - https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:52:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:52:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:52:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 01:52:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 01:52:38 INFO - Downloaded 110526158 bytes. 01:52:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:52:38 INFO - mkdir: /builds/slave/test/properties 01:52:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:52:38 INFO - Writing to file /builds/slave/test/properties/build_url 01:52:38 INFO - Contents: 01:52:38 INFO - build_url:https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:52:39 INFO - Running post-action listener: _resource_record_post_action 01:52:39 INFO - Running post-action listener: set_extra_try_arguments 01:52:39 INFO - ##### 01:52:39 INFO - ##### Running populate-webroot step. 01:52:39 INFO - ##### 01:52:39 INFO - Running pre-action listener: _resource_record_pre_action 01:52:39 INFO - Running main action method: populate_webroot 01:52:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:52:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:52:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:52:39 INFO - '--tppagecycles', 01:52:39 INFO - '7'], 01:52:39 INFO - 'tests': ['tsvgm']}}, 01:52:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:52:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:52:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:52:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:52:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:52:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:52:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:39 INFO - 'talos_options': ['--disable-e10s'], 01:52:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:52:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:52:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:39 INFO - 'talos_options': ['--disable-e10s'], 01:52:39 INFO - 'tests': ['damp', 'tps']}, 01:52:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:39 INFO - 'tests': ['damp', 'tps']}, 01:52:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:52:39 INFO - 'tests': ['dromaeo_dom']}, 01:52:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:52:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:52:39 INFO - 'tests': ['a11yr', 01:52:39 INFO - 'ts_paint', 01:52:39 INFO - 'tpaint', 01:52:39 INFO - 'sessionrestore', 01:52:39 INFO - 'sessionrestore_no_auto_restore', 01:52:39 INFO - 'tabpaint']}, 01:52:39 INFO - 'other-e10s': {'tests': ['a11yr', 01:52:39 INFO - 'ts_paint', 01:52:39 INFO - 'tpaint', 01:52:39 INFO - 'sessionrestore', 01:52:39 INFO - 'sessionrestore_no_auto_restore', 01:52:39 INFO - 'tabpaint']}, 01:52:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:52:39 INFO - 'ts_paint', 01:52:39 INFO - 'tpaint', 01:52:39 INFO - 'sessionrestore', 01:52:39 INFO - 'sessionrestore_no_auto_restore', 01:52:39 INFO - 'tabpaint']}, 01:52:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:52:39 INFO - 'ts_paint', 01:52:39 INFO - 'tpaint', 01:52:39 INFO - 'sessionrestore', 01:52:39 INFO - 'sessionrestore_no_auto_restore', 01:52:39 INFO - 'tabpaint']}, 01:52:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:52:39 INFO - 'tests': ['a11yr', 01:52:39 INFO - 'ts_paint', 01:52:39 INFO - 'tpaint', 01:52:39 INFO - 'sessionrestore', 01:52:39 INFO - 'sessionrestore_no_auto_restore', 01:52:39 INFO - 'tabpaint']}, 01:52:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:52:39 INFO - 'tests': ['a11yr', 01:52:39 INFO - 'ts_paint', 01:52:39 INFO - 'tpaint', 01:52:39 INFO - 'sessionrestore', 01:52:39 INFO - 'sessionrestore_no_auto_restore', 01:52:39 INFO - 'tabpaint']}, 01:52:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:52:39 INFO - 'tests': ['tsvgx', 01:52:39 INFO - 'tsvgr_opacity', 01:52:39 INFO - 'tart', 01:52:39 INFO - 'tscrollx', 01:52:39 INFO - 'cart']}, 01:52:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:52:39 INFO - 'tsvgr_opacity', 01:52:39 INFO - 'tart', 01:52:39 INFO - 'tscrollx', 01:52:39 INFO - 'cart']}, 01:52:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:39 INFO - 'talos_options': ['--disable-e10s'], 01:52:39 INFO - 'tests': ['tp5o']}, 01:52:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:39 INFO - 'tests': ['tp5o']}, 01:52:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:39 INFO - 'talos_options': ['--disable-e10s', 01:52:39 INFO - '--xperf_path', 01:52:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:52:39 INFO - 'tests': ['tp5n']}, 01:52:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:52:39 INFO - 'talos_options': ['--xperf_path', 01:52:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:52:39 INFO - 'tests': ['tp5n']}}, 01:52:39 INFO - 'talos.zip': {'path': '', 01:52:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:52:39 INFO - Running post-action listener: _resource_record_post_action 01:52:39 INFO - ##### 01:52:39 INFO - ##### Running create-virtualenv step. 01:52:39 INFO - ##### 01:52:39 INFO - Running pre-action listener: _resource_record_pre_action 01:52:39 INFO - Running main action method: create_virtualenv 01:52:39 INFO - Creating virtualenv /builds/slave/test/build/venv 01:52:39 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 01:52:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:52:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:52:39 INFO - Using real prefix '/tools/python27' 01:52:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:52:40 INFO - Installing distribute.............................................................................................................................................................................................done. 01:52:43 INFO - Installing pip.................done. 01:52:43 INFO - Return code: 0 01:52:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:52:43 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')]} 01:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 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')]} 01:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 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 0x10306da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10307d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88ca586050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f0ce00>, '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 0x102d35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f07b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:52:43 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 01:52:43 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 01:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 01:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:43 INFO - 'HOME': '/Users/cltbld', 01:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:43 INFO - 'LOGNAME': 'cltbld', 01:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:43 INFO - 'MOZ_NO_REMOTE': '1', 01:52:43 INFO - 'NO_EM_RESTART': '1', 01:52:43 INFO - 'PAGER': '/bin/cat', 01:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:43 INFO - 'PWD': '/builds/slave/test', 01:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:43 INFO - 'SHELL': '/bin/bash', 01:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 01:52:43 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 01:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:43 INFO - 'USER': 'cltbld', 01:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:43 INFO - 'XPC_FLAGS': '0x0', 01:52:43 INFO - 'XPC_SERVICE_NAME': '0', 01:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:43 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 01:52:43 INFO - Cleaning up... 01:52:43 INFO - Return code: 0 01:52:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:52:43 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')]} 01:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 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')]} 01:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:43 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10306da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10307d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88ca586050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f0ce00>, '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 0x102d35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f07b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:52:43 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>=0.7.1'] in /builds/slave/test/build 01:52:43 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>=0.7.1 01:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 01:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:43 INFO - 'HOME': '/Users/cltbld', 01:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:43 INFO - 'LOGNAME': 'cltbld', 01:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:43 INFO - 'MOZ_NO_REMOTE': '1', 01:52:43 INFO - 'NO_EM_RESTART': '1', 01:52:43 INFO - 'PAGER': '/bin/cat', 01:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:43 INFO - 'PWD': '/builds/slave/test', 01:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:43 INFO - 'SHELL': '/bin/bash', 01:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 01:52:43 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 01:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:43 INFO - 'USER': 'cltbld', 01:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:43 INFO - 'XPC_FLAGS': '0x0', 01:52:43 INFO - 'XPC_SERVICE_NAME': '0', 01:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:43 INFO - Downloading/unpacking psutil>=0.7.1 01:52:43 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 01:52:43 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 01:52:43 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 01:52:43 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 01:52:43 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 01:52:43 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 01:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:52:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:52:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:52:47 INFO - Installing collected packages: psutil 01:52:47 INFO - Running setup.py install for psutil 01:52:47 INFO - building 'psutil._psutil_osx' extension 01:52:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:52:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:52:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:52:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 01:52:48 INFO - building 'psutil._psutil_posix' extension 01:52:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 01:52:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:52:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:52:48 INFO - ^ 01:52:48 INFO - 1 warning generated. 01:52:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 01:52:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:52:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:52:48 INFO - Successfully installed psutil 01:52:48 INFO - Cleaning up... 01:52:48 INFO - Return code: 0 01:52:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:52: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')]} 01:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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')]} 01:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10306da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10307d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88ca586050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f0ce00>, '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 0x102d35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f07b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:52: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 01:52: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 mozsystemmonitor==0.0.0 01:52:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 01:52:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:48 INFO - 'HOME': '/Users/cltbld', 01:52:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:48 INFO - 'LOGNAME': 'cltbld', 01:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:48 INFO - 'MOZ_NO_REMOTE': '1', 01:52:48 INFO - 'NO_EM_RESTART': '1', 01:52:48 INFO - 'PAGER': '/bin/cat', 01:52:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:48 INFO - 'PWD': '/builds/slave/test', 01:52:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:48 INFO - 'SHELL': '/bin/bash', 01:52:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 01:52:48 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 01:52:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:48 INFO - 'USER': 'cltbld', 01:52:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:48 INFO - 'XPC_FLAGS': '0x0', 01:52:48 INFO - 'XPC_SERVICE_NAME': '0', 01:52:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52: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 01:52:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:52:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:52:51 INFO - Installing collected packages: mozsystemmonitor 01:52:51 INFO - Running setup.py install for mozsystemmonitor 01:52:51 INFO - Successfully installed mozsystemmonitor 01:52:51 INFO - Cleaning up... 01:52:51 INFO - Return code: 0 01:52:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10306da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10307d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88ca586050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f0ce00>, '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 0x102d35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f07b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:52:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:52:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 01:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:51 INFO - 'HOME': '/Users/cltbld', 01:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:51 INFO - 'LOGNAME': 'cltbld', 01:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:51 INFO - 'MOZ_NO_REMOTE': '1', 01:52:51 INFO - 'NO_EM_RESTART': '1', 01:52:51 INFO - 'PAGER': '/bin/cat', 01:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:51 INFO - 'PWD': '/builds/slave/test', 01:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:51 INFO - 'SHELL': '/bin/bash', 01:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 01:52:51 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 01:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:51 INFO - 'USER': 'cltbld', 01:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:51 INFO - 'XPC_FLAGS': '0x0', 01:52:51 INFO - 'XPC_SERVICE_NAME': '0', 01:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:52 INFO - Downloading/unpacking blobuploader==1.2.4 01:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:54 INFO - Downloading blobuploader-1.2.4.tar.gz 01:52:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:52:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:52:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:52:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:52:55 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 01:52:55 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 01:52:55 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 01:52:55 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 01:52:55 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 01:52:55 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 01:52:55 INFO - Downloading docopt-0.6.1.tar.gz 01:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:52:55 INFO - Installing collected packages: blobuploader, requests, docopt 01:52:55 INFO - Running setup.py install for blobuploader 01:52:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:52:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:52:56 INFO - Running setup.py install for requests 01:52:56 INFO - Running setup.py install for docopt 01:52:56 INFO - Successfully installed blobuploader requests docopt 01:52:56 INFO - Cleaning up... 01:52:56 INFO - Return code: 0 01:52:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:52:56 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')]} 01:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:56 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')]} 01:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:52:56 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 0x10306da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10307d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88ca586050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f0ce00>, '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 0x102d35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f07b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:52:56 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 01:52:56 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 01:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 01:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:52:56 INFO - 'HOME': '/Users/cltbld', 01:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:52:56 INFO - 'LOGNAME': 'cltbld', 01:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:52:56 INFO - 'MOZ_NO_REMOTE': '1', 01:52:56 INFO - 'NO_EM_RESTART': '1', 01:52:56 INFO - 'PAGER': '/bin/cat', 01:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:52:56 INFO - 'PWD': '/builds/slave/test', 01:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:52:56 INFO - 'SHELL': '/bin/bash', 01:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 01:52:56 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 01:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:52:56 INFO - 'USER': 'cltbld', 01:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:52:56 INFO - 'XPC_FLAGS': '0x0', 01:52:56 INFO - 'XPC_SERVICE_NAME': '0', 01:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:52:57 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-X9YTvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:52:57 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-MNLh1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:52:57 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xdOFfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:52:57 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-6G08gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:52:57 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qpXIcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:52:57 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-zYxH9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:52:57 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-3HWlLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:52:57 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-QzpOiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:52:57 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-fnfey3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:52:57 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-2dxuBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:52:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-jwIbHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:52:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-mGpcuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:52:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Zya0OC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:52:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Ue2NOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-daKhDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:52:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-5jGtsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:52:58 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-BmyR0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:52:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:52:58 INFO - Running setup.py install for manifestparser 01:52:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:52:58 INFO - Running setup.py install for mozcrash 01:52:59 INFO - Running setup.py install for mozdebug 01:52:59 INFO - Running setup.py install for mozdevice 01:52:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:52:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:52:59 INFO - Running setup.py install for mozfile 01:52:59 INFO - Running setup.py install for mozhttpd 01:52:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:52:59 INFO - Running setup.py install for mozinfo 01:52:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:52:59 INFO - Running setup.py install for mozInstall 01:52:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:52:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:52:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:52:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:52:59 INFO - Running setup.py install for mozleak 01:53:00 INFO - Running setup.py install for mozlog 01:53:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:53:00 INFO - Running setup.py install for moznetwork 01:53:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:53:00 INFO - Running setup.py install for mozprocess 01:53:00 INFO - Running setup.py install for mozprofile 01:53:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:53:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:53:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:53:00 INFO - Running setup.py install for mozrunner 01:53:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:53:00 INFO - Running setup.py install for mozscreenshot 01:53:01 INFO - Running setup.py install for moztest 01:53:01 INFO - Running setup.py install for mozversion 01:53:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:53:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:53:01 INFO - Cleaning up... 01:53:01 INFO - Return code: 0 01:53:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:53:01 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')]} 01:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:01 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')]} 01:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:01 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 0x10306da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10307d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88ca586050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f0ce00>, '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 0x102d35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f07b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:01 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 01:53:01 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 01:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 01:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:01 INFO - 'HOME': '/Users/cltbld', 01:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:01 INFO - 'LOGNAME': 'cltbld', 01:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:01 INFO - 'MOZ_NO_REMOTE': '1', 01:53:01 INFO - 'NO_EM_RESTART': '1', 01:53:01 INFO - 'PAGER': '/bin/cat', 01:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:01 INFO - 'PWD': '/builds/slave/test', 01:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:01 INFO - 'SHELL': '/bin/bash', 01:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 01:53:01 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 01:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:01 INFO - 'USER': 'cltbld', 01:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:01 INFO - 'XPC_FLAGS': '0x0', 01:53:01 INFO - 'XPC_SERVICE_NAME': '0', 01:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:53:01 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-RF7WJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:53:01 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-TOYkL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:53:01 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-mKKacY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:53:01 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-3LJiOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:53:02 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)) 01:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:53:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-dTeah3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:53:02 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)) 01:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:53:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-3rAcGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:53:02 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)) 01:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:53:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Z5vPpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:53:02 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)) 01:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:53:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Pll9Fi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:53:02 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)) 01:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:53:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-l2U251-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:53:02 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)) 01:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:53:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-cU02iP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 01:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:53:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-LbDurG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:53:02 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)) 01:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:53:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-QZ8XcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:53:02 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)) 01:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:53:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-_2rWIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:53:02 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)) 01:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:53:02 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Bup5Xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:53:03 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)) 01:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:53:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-bjKDe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:53:03 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)) 01:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:53:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-OK60ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:53:03 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)) 01:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:53:03 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-vja5N7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:53:03 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)) 01:53:03 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)) 01:53:03 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)) 01:53:03 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)) 01:53:03 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)) 01:53:03 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)) 01:53:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:53:03 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 01:53:03 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 01:53:03 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 01:53:03 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 01:53:03 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 01:53:03 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 01:53:05 INFO - Downloading blessings-1.6.tar.gz 01:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:53:05 INFO - Installing collected packages: blessings 01:53:05 INFO - Running setup.py install for blessings 01:53:06 INFO - Successfully installed blessings 01:53:06 INFO - Cleaning up... 01:53:06 INFO - Return code: 0 01:53:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:53:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:53:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:53:06 INFO - Reading from file tmpfile_stdout 01:53:06 INFO - Current package versions: 01:53:06 INFO - blessings == 1.6 01:53:06 INFO - blobuploader == 1.2.4 01:53:06 INFO - docopt == 0.6.1 01:53:06 INFO - manifestparser == 1.1 01:53:06 INFO - mozInstall == 1.12 01:53:06 INFO - mozcrash == 0.17 01:53:06 INFO - mozdebug == 0.1 01:53:06 INFO - mozdevice == 0.48 01:53:06 INFO - mozfile == 1.2 01:53:06 INFO - mozhttpd == 0.7 01:53:06 INFO - mozinfo == 0.9 01:53:06 INFO - mozleak == 0.1 01:53:06 INFO - mozlog == 3.1 01:53:06 INFO - moznetwork == 0.27 01:53:06 INFO - mozprocess == 0.22 01:53:06 INFO - mozprofile == 0.28 01:53:06 INFO - mozrunner == 6.11 01:53:06 INFO - mozscreenshot == 0.1 01:53:06 INFO - mozsystemmonitor == 0.0 01:53:06 INFO - moztest == 0.7 01:53:06 INFO - mozversion == 1.4 01:53:06 INFO - psutil == 3.1.1 01:53:06 INFO - requests == 1.2.3 01:53:06 INFO - wsgiref == 0.1.2 01:53:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:06 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 0x10306da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10307d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88ca586050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f0ce00>, '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 0x102d35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f07b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:06 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 01:53:06 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 01:53:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 01:53:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:06 INFO - 'HOME': '/Users/cltbld', 01:53:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:06 INFO - 'LOGNAME': 'cltbld', 01:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:06 INFO - 'MOZ_NO_REMOTE': '1', 01:53:06 INFO - 'NO_EM_RESTART': '1', 01:53:06 INFO - 'PAGER': '/bin/cat', 01:53:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:06 INFO - 'PWD': '/builds/slave/test', 01:53:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:06 INFO - 'SHELL': '/bin/bash', 01:53:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 01:53:06 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 01:53:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:06 INFO - 'USER': 'cltbld', 01:53:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:06 INFO - 'XPC_FLAGS': '0x0', 01:53:06 INFO - 'XPC_SERVICE_NAME': '0', 01:53:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:53:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:06 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)) 01:53:06 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)) 01:53:06 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)) 01:53:06 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)) 01:53:06 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)) 01:53:06 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)) 01:53:06 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)) 01:53:06 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)) 01:53:06 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)) 01:53:06 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)) 01:53:06 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)) 01:53:06 INFO - Cleaning up... 01:53:06 INFO - Return code: 0 01:53:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10306da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10307d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88ca586050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f0ce00>, '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 0x102d35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f07b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:53:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:53:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 01:53:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:06 INFO - 'HOME': '/Users/cltbld', 01:53:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:06 INFO - 'LOGNAME': 'cltbld', 01:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:06 INFO - 'MOZ_NO_REMOTE': '1', 01:53:06 INFO - 'NO_EM_RESTART': '1', 01:53:06 INFO - 'PAGER': '/bin/cat', 01:53:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:06 INFO - 'PWD': '/builds/slave/test', 01:53:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:06 INFO - 'SHELL': '/bin/bash', 01:53:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 01:53:06 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 01:53:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:06 INFO - 'USER': 'cltbld', 01:53:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:06 INFO - 'XPC_FLAGS': '0x0', 01:53:06 INFO - 'XPC_SERVICE_NAME': '0', 01:53:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:53:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:06 INFO - Downloading/unpacking jsonschema 01:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:53:09 INFO - Downloading/unpacking functools32 (from jsonschema) 01:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:09 INFO - Downloading functools32-3.2.3-2.tar.gz 01:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:53:09 INFO - warning: no files found matching '*.txt' 01:53:09 INFO - no previously-included directories found matching 'build' 01:53:09 INFO - no previously-included directories found matching 'dist' 01:53:09 INFO - no previously-included directories found matching '.git*' 01:53:09 INFO - Installing collected packages: jsonschema, functools32 01:53:09 INFO - Running setup.py install for functools32 01:53:09 INFO - warning: no files found matching '*.txt' 01:53:09 INFO - no previously-included directories found matching 'build' 01:53:09 INFO - no previously-included directories found matching 'dist' 01:53:09 INFO - no previously-included directories found matching '.git*' 01:53:09 INFO - Successfully installed jsonschema functools32 01:53:09 INFO - Cleaning up... 01:53:10 INFO - Return code: 0 01:53:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:53:10 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')]} 01:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:53:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:10 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')]} 01:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:53:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:53:10 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 0x10306da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10307d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88ca586050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f0ce00>, '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 0x102d35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f07b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:53:10 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 01:53:10 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 01:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 01:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:10 INFO - 'HOME': '/Users/cltbld', 01:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:10 INFO - 'LOGNAME': 'cltbld', 01:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:10 INFO - 'MOZ_NO_REMOTE': '1', 01:53:10 INFO - 'NO_EM_RESTART': '1', 01:53:10 INFO - 'PAGER': '/bin/cat', 01:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:10 INFO - 'PWD': '/builds/slave/test', 01:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:10 INFO - 'SHELL': '/bin/bash', 01:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 01:53:10 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 01:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:10 INFO - 'USER': 'cltbld', 01:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:10 INFO - 'XPC_FLAGS': '0x0', 01:53:10 INFO - 'XPC_SERVICE_NAME': '0', 01:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:53:10 INFO - Downloading/unpacking flake8 01:53:10 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 01:53:10 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 01:53:10 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 01:53:10 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 01:53:10 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 01:53:10 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 01:53:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:53:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:53:12 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 01:53:12 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 01:53:12 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 01:53:12 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 01:53:12 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 01:53:12 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 01:53:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:53:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:53:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:53:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:53:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:53:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:53:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:53:14 INFO - no previously-included directories found matching 'docs/_build' 01:53:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:53:14 INFO - Running setup.py install for pep8 01:53:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:53:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:53:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:53:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:53:14 INFO - no previously-included directories found matching 'docs/_build' 01:53:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:53:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:53:14 INFO - Cleaning up... 01:53:14 INFO - Return code: 0 01:53:14 INFO - Running post-action listener: _resource_record_post_action 01:53:14 INFO - Running post-action listener: _start_resource_monitoring 01:53:14 INFO - Starting resource monitoring. 01:53:14 INFO - ##### 01:53:14 INFO - ##### Running install step. 01:53:14 INFO - ##### 01:53:14 INFO - Running pre-action listener: _resource_record_pre_action 01:53:14 INFO - Running main action method: install 01:53:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:53:14 INFO - Reading from file tmpfile_stdout 01:53:14 INFO - Detecting whether we're running mozinstall >=1.0... 01:53:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:53:14 INFO - Reading from file tmpfile_stdout 01:53:14 INFO - Output received: 01:53:14 INFO - Usage: mozinstall [options] installer 01:53:14 INFO - Options: 01:53:14 INFO - -h, --help show this help message and exit 01:53:14 INFO - -d DEST, --destination=DEST 01:53:14 INFO - Directory to install application into. [default: 01:53:14 INFO - "/builds/slave/test"] 01:53:14 INFO - --app=APP Application being installed. [default: firefox] 01:53:14 INFO - mkdir: /builds/slave/test/build/application 01:53:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:53:48 INFO - Reading from file tmpfile_stdout 01:53:48 INFO - Output received: 01:53:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:53:48 INFO - Running post-action listener: _resource_record_post_action 01:53:48 INFO - ##### 01:53:48 INFO - ##### Running run-tests step. 01:53:48 INFO - ##### 01:53:48 INFO - Running pre-action listener: _resource_record_pre_action 01:53:48 INFO - Running main action method: run_tests 01:53:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:53:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:53:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:53:48 INFO - 2016-04-30 01:53:48.383 screenresolution[1649:8517] starting screenresolution argv=screenresolution get 01:53:48 INFO - 2016-04-30 01:53:48.386 screenresolution[1649:8517] Display 0: 1600x1200x32@60 01:53:48 INFO - 2016-04-30 01:53:48.391 screenresolution[1650:8519] starting screenresolution argv=screenresolution list 01:53:48 INFO - Available Modes on Display 0 01:53:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:53:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:53:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:53:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:53:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:53:48 INFO - Intel Iris: 01:53:48 INFO - Chipset Model: Intel Iris 01:53:48 INFO - Type: GPU 01:53:48 INFO - Bus: Built-In 01:53:48 INFO - VRAM (Dynamic, Max): 1536 MB 01:53:48 INFO - Vendor: Intel (0x8086) 01:53:48 INFO - Device ID: 0x0a2e 01:53:48 INFO - Revision ID: 0x0009 01:53:48 INFO - Displays: 01:53:48 INFO - Display: 01:53:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:53:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:53:48 INFO - Display Serial Number: 1600x1200 60 01:53:48 INFO - Main Display: Yes 01:53:48 INFO - Mirror: Off 01:53:48 INFO - Online: Yes 01:53:48 INFO - Rotation: Supported 01:53:48 INFO - Return code: 0 01:53:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:53:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:53:48 INFO - Python 2.7.3 01:53:48 INFO - Return code: 0 01:53:48 INFO - grabbing minidump binary from tooltool 01:53: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')]} 01:53:48 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f0ce00>, '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 0x102d35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f07b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:53:48 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:53:48 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 01:53:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:53:48 INFO - Return code: 0 01:53:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:53:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:53:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:53:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:53:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:53:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:53:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:53:49 INFO - Return code: 0 01:53:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 01:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0147 --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 01:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render', 01:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:53:49 INFO - 'HOME': '/Users/cltbld', 01:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:53:49 INFO - 'LOGNAME': 'cltbld', 01:53:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:53:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:53:49 INFO - 'MOZ_NO_REMOTE': '1', 01:53:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:53:49 INFO - 'NO_EM_RESTART': '1', 01:53:49 INFO - 'PAGER': '/bin/cat', 01:53:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:53:49 INFO - 'PWD': '/builds/slave/test', 01:53:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:53:49 INFO - 'SHELL': '/bin/bash', 01:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners', 01:53:49 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 01:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:53:49 INFO - 'USER': 'cltbld', 01:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:53:49 INFO - 'XPC_FLAGS': '0x0', 01:53:49 INFO - 'XPC_SERVICE_NAME': '0', 01:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:53:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 01:53:50 INFO - mozversion application_buildid: 20160430005741 01:53:50 INFO - mozversion application_changeset: aea9eee926979ec130fa3a1a32bdcede88b82a35 01:53:50 INFO - mozversion application_display_name: Nightly 01:53:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:53:50 INFO - mozversion application_name: Firefox 01:53:50 INFO - mozversion application_remotingname: firefox 01:53:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:53:50 INFO - mozversion application_vendor: Mozilla 01:53:50 INFO - mozversion application_version: 49.0a1 01:53:50 INFO - mozversion platform_buildid: 20160430005741 01:53:50 INFO - mozversion platform_changeset: aea9eee926979ec130fa3a1a32bdcede88b82a35 01:53:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:53:50 INFO - mozversion platform_version: 49.0a1 01:53:50 INFO - using testdate: 1462006430 01:53:50 INFO - actual date: 1462006430 01:53:50 INFO - starting webserver on 'localhost:49281' 01:53:50 INFO - SUITE-START | Running 2 tests 01:53:50 INFO - TEST-START | dromaeo_css 01:53:50 INFO - operating with platform_type : mac_ 01:53:50 INFO - Initialising browser for dromaeo_css test... 01:53:50 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpePu1H0/profile http://localhost:49281/getInfo.html) 01:53:52 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 01:53:52 INFO - PROCESS | 1668 | colorDepth:24 01:53:52 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 01:53:52 INFO - PROCESS | 1668 | __metrics 01:53:52 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:53:52 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:53:53 INFO - TEST-INFO | 1668: exit 0 01:53:53 INFO - Browser initialized. 01:53:53 INFO - Running cycle 1/1 for dromaeo_css test... 01:53:53 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpePu1H0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:54:05 INFO - PROCESS | 1671 | RSS: Main: 239874048 01:54:05 INFO - PROCESS | 1671 | 01:54:56 INFO - PROCESS | 1671 | 1462006496708 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] 01:57:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 01:57:09 INFO - PROCESS | 1671 | RSS: Main: 350842880 01:57:09 INFO - PROCESS | 1671 | 02:00:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 02:00:12 INFO - PROCESS | 1671 | RSS: Main: 368418816 02:00:12 INFO - PROCESS | 1671 | 02:03:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 02:03:15 INFO - PROCESS | 1671 | RSS: Main: 370515968 02:03:15 INFO - PROCESS | 1671 | 02:06:17 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 02:06:18 INFO - PROCESS | 1671 | RSS: Main: 369332224 02:06:18 INFO - PROCESS | 1671 | 02:09:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 02:09:22 INFO - PROCESS | 1671 | RSS: Main: 343683072 02:09:22 INFO - PROCESS | 1671 | 02:12:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 02:12:25 INFO - PROCESS | 1671 | RSS: Main: 404787200 02:12:25 INFO - PROCESS | 1671 | 02:12:25 INFO - PROCESS | 1671 | __start_tp_report 02:12:25 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 02:12:25 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 02:12:25 INFO - PROCESS | 1671 | |i|pagename|runs| 02:12:25 INFO - PROCESS | 1671 | |0;dojo.html;2884.7115288471155;3460.394430974579;3487.0584942065643;3510.1400156961517;3549.148204430936;4696.788644510997;5617;5715.657060576368;5727.169560413738;5750.453706897849;5743.54051675866;5852.736626851792;5870.236869207008;5873.236479257691;5874.412558744123;5213.504717051893;5216.504432078966;5234.555062819665;5249.422563518029;5270.762815673284;5121;5275.86810329741;5277.182036784286;5290.894182116355;5294.444083371249;5258.684478931276;5286.3392075990505;5326.280952071459;5330.466953304677;5337.7598008089535;5116.002379535992;5199.14214154664;5202.2456743771945;5208.062548741225;5241.397239317451;5158.561522270612;5188.273641690166;5200.3759548854005;5217.269582258449;5257.185136303879;5709.286339207599;5722.885542289173;5732.398098199671;5757.395473475267;5792.102224155263;6206.782762603332;6212.906806397862;6218.4714299284615;6225.968870155665;6234.9688251558455;6062.151298818169;6146.89355915935;6200.224971878515;6250.96874515629;6277.560570760049;5758.7696492140685;5778;5781.739821708034;5788.6526808392055;5804.506616937565;6211.130441738161;6211.503079753609;6242.28213755424;6246.500279977591;6258.217722784652;6087.6956152192215;6119.84700382494;6123.4795042421;6138.878900475427;6143.877122457526;6197.442230199304;6215.875682486325;6216.2540495140875;6219.657918814418;6235.594686345373;6187.319394866561;6211.3478084800445;6226.315105338409;6226.910290699109;6237.282712488122;6107.492261292593;6137.263528376623;6200.3799620037635;6203.937960620427;6207.317195108534;4905.877353066202;4910.92633610489;4935.728534930541;4951.5048495150195;4954.380702412199;4471.3516540102155;4491.1915855145735;4557.977210113994;4581.221192397301;4586.334981427674;5765.769369225268;5769.307683078057;5770.24986751728;5777.971110144506;5782.971085144632;6117.827404800917;6157.230346206724;6182.876342473215;6204.255489341218;6229;5764.365919748824;5779.508741756914;5845.941540584625;5895.322037965689;5908.438698323653;5421.83734487966;5423.674579525241;5432.728363581884;5436.592255580768;5457.426970168075;5365.812196573102;6164.845878853064;6331.3352098029045;6340.556161068866;6351.2060992375955;6138.6336396323695;6170.6914654268;6206.906896396467;6208.72060757258;6221.3156552780065;6302.810915672537;6344.238691357066;6353.809385718436;6364.713587888648;6383.872322553616;5419.756110975094;5988.820335389945;6246.500279977682;6251.343608920998;6264.185655864709;6115.052166914135;6156.876862462636;6196.504279657526;6258.342873998165;6259.436650701458;5461.808836690697;6149.354317796567;6162.630242185483;6164.506839452923;6171.84570385744;6025.849353766191;6166.229221347332;6170.352113028068;6190.3190649029475;6190.442860142609;5826.854328641818;5923.763049477972;5944;6023.266951350927;6087.178230938766;6157.96921015392;6158.969205154125;6158.983767678283;6165.5375846810775;6166.321704612579;5790.1893734877995;6007.639541627516;6013.9699301503215;6030.5778595497895;6039.184710064085 02:12:25 INFO - PROCESS | 1671 | |1;ext.html;502.11627535537446;509.5821426430326;511.2433598274554;587.4947545111205;593.4955288005193;27623.447531049365;31034.379312413737;38031.80984095079;38965.61034389655;39110.41334379987;43008.139837203234;43555.91110222246;43594.56405435945;43601;43616.781916090375;9492.905070949306;9772.267079968995;9779.755506112333;9780.804383912353;9802.705918822447;8508.489490630582;8745.693900713477;11416.71458213543;11664.941675291611;11685.240459370156;11614.535418583246;11780.646580602595;11820.527178912833;11831.940840295829;11838.289702617827;8470.110638383005;8702.30381569473;11550.653480395602;11709.414529273545;11732.882671173264;8580.099089595566;8688.739337819876;11357.829632555518;11627.12796540263;11643.41782910851;37908.43137352942;38493.23013539742;38533.68797872137;38572.035699107466;38592;3125.1249650098007;3277.8197199154038;3598.298331825295;3694.8152592370543;3768.9811550942068;27804.860975695196;28126;28135.43729125406;28562.714372856215;28602.570961435595;2694.151342327161;2699.1632593895956;2715.375463643373;2807.6490438695164;2920.8977685780897;351266;355362.57099431986;355386;355905;355934;59786.10320845191;60245.69877150674;60747.69626151841;60782.48043798852;60825;63735.68132159402;64185.67907160434;64195.67902160553;64295;64297;16019.519414417586;16323.10222937726;16377.754333685005;16660.916695416447;16942.91528542374;11473.483693233658;11750.882491174978;12536.81194782079;12870.806937895939;12928.288944107977;18820.247190112517;18933.81066189348;18946;18956.715649265272;18968.43094707161;6565.704543295563;6845.418139458102;7908.5254884705955;8098.271155596025;8138.470999385021;933.2673851026912;938.2212763406357;972.2125078686287;1056.2395075545594;1088.9891101089047;25582.744172558407;28837;29351.932866688032;29548.704512955024;29555.408891822473;1064.6965614799744;1071.3678929431674;1116.3134672176552;1141.0244241173843;1212.678640160358;14902.316675476011;15004.699906002037;15281.923590381977;15318.617034574225;15346.462873799363;558849;565772.1711391417;567245;568799;569120;49190.508094918594;57260.71369643125;59276.814463711344;59287.40712592819;59287.70356148192;1277.5337001994205;1320.1155225998796;1443.1124858212333;1443.4947768281017;1446.9493567725078;26607.866960665073;27528.03651872175;29023;29679.85160074186;30707.539386909662;50361.99276014386;50555.74722126366;51621.48378516317;51643;51643.74178129085;544641;591199;592121;592159;592282;32794.34411311713;39269.6073039266;39316.410253846785;39844.60155398409;40701.557906525704;44192.67421977346;44214.55785442233;44492.11015779731;45662.543374567154;45898.54101458943;35182.47226291659;37918.94348820719;38178.42732358961;38502.22995540129;39174.412383813695;37644.62355376521;37841.24317513689;37897.62102378941;38681.41977870278;38840.80579597084;34687.306253875286;35261.47107793334;35805;35883.28233435265;37303.626963730014;29256.414871702873;31599.36801264008;31835.840820795747;31846.6815331853;32178.83910580526;21160.047797849442;23104.653430198898;23658.52682946366;23729.16947906815;23742.525149497258 02:12:25 INFO - PROCESS | 1671 | |2;jquery.html;386.36635917095964;387.85649309755377;389.95710471848093;392.88134106097453;398.6591464298025;19586.608267834672;19972.300969466072;20634.793652063476;20647.896760516196;20678.276260330873;19123.521911952208;19746.111424985873;20984.8950755246;21414.25050123246;21433.785662143375;5797.855053623662;6125.724342404603;6142.600730952474;6147.262328520584;6175.567710260284;5125.205593133071;5993.88012239755;6072.2106126203535;6082.908756368658;6084.330723620398;5801.564882633798;5978.551608629348;6005.969970150165;6010.819675409744;6013.819585412422;5894.3810899855325;5992.760289588411;6002;6004.159417681528;6005.309389420229;5017.197248440232;5167.509086636764;5192.7403629818355;5203.907179492311;5205.713685747282;23574.88212558926;23594.882025589934;23860.40348991272;25123.748762512507;26661.200163995112;1861.171778558542;1963.8919859407727;2219.079082180893;2320.7795259450327;2411.4574220800355;9705.369151005161;9797.559109840075;10255.128314093312;11857.822132668018;15135.772963405558;1747.4146161035942;1797.1104303369784;1805.4673871207895;1810.2216047099741;1810.6650269700017;76810.69567912971;117445;125872;126905.36547317296;127831;9336;11359.545618175345;11514.53941842317;11607.071434285237;11714.88285117155;14483.710325793425;14532.200728959797;14564.344604492824;14567.562973110824;14579.854201458062;8468.703595374132;8633.956830215935;8654.610542525601;8748.69379571712;8759.16787905148;8543.444676096158;8556.786080348042;8567.27178189861;8665.696700615574;8693.391462597558;4428.933565996513;4532.297493888453;4538;4619.745913974696;4621.815127394867;1634.525987463646;1886.2643569008098;1956.9608607827763;1958.5691147947714;1963.3226536844766;666.9733210671616;834.9332053435832;862.4954401674964;907.9118028534476;984.7833476635129;18935.715964260547;19671.21315147378;19765.505862353555;19921.800781992286;19975.900120499304;749.5465243527697;933.939293945905;939.9718008459757;967.3615413826981;979.8971108033696;10325;10548.41983690889;10931.945340273247;10952.616658416917;11032.944835275772;596830;596985;598744.0062799659;609751;609915;126300;126977.73022269843;129961;132636;133793.99309010373;954.1079091049891;957.4877440569401;978.7014960436993;978.8727465429448;1001.8597396364515;13450.663733406744;14407.639809004859;17949;18290.451286461426;18291.725624115916;50120.49879501158;52294.73852630713;52661;53141.20288195603;53865.73067134639;76425.23574764181;77489;77884.61057694675;77992.61003694945;78137.218627813;8001;8006.399520036137;8104.432689711663;8283.006039275326;8302.252797248277;7278.454115941219;11164.776704465703;11278.210525263154;12314.568990085663;12885.291308978096;10396.116330112025;10523.526441310007;10779.838302425627;10952.561897524329;11278.60524881658;10415.68752937413;10824.4046577439;10855.18586106061;11130.10959123252;11135.72160695989;10511.789764204826;10845.457727113771;10978.780424391307;11138.721531961442;11215.383153926612;7931.2068793120225;7932.6430310635005;8182.4681395709085;8213.014438267446;8289.709860154866;10102;10908.563657453613;11247.606333778278;11259.887401125883;11294 02:12:25 INFO - PROCESS | 1671 | |3;mootools.html;1370.7601169795296;1544.2741911301687;1554.7046061248361;1768.8231176882305;1918.7217853411246;7589.848203035936;8370.874436883443;8567.65729370826;8570.65717371306;8573.52845593493;8208.589570521464;8464.957675211615;8474.95762521188;8479.448835825682;8486.321094312456;2307.769223077687;2349.5770761262966;2350.518143780521;2351.247600767755;2378.8691621960784;2276.3171048685413;2317.8029867461287;2321.663358812971;2328.2654422455294;2333.369990102671;2293.954120917589;2319.895604697784;2330.161141988891;2340.0873659272897;2347.9882600586975;2299.1033496936207;2318;2324.1052194904914;2325.7209134903837;2335.6730057791924;2288.1305104060352;2291.9312420627243;2293.0942277800177;2293.380787187467;2297.437127903674;8447.915520844774;8470.788230294293;8547.957260213721;8554.700585479479;8558.486490810572;5066.670666406709;5252.395974462947;5267.104592219293;5267.3679158500845;5276.709780962045;3396.694297513211;3521.630228825962;3582.283543291326;3592.515010473579;3601.3877640801124;1009.2783659683362;1020.9489525523768;1021.4688362051766;1027.9640212592599;1031.0617338222182;11185;11311;11519.193656444053;11566.710832229177;11569.01663358624;4345.435093437833;4469.128519938581;4469.687121901436;4506.662000349987;4571.862844114682;9055.81888362229;9072.90927090734;9093.090690930987;9111.362204645611;9118.224950879117;5078.13671675816;5143.691378517301;5175;5185.585153187736;5188.870278242998;4252.957470425318;4430.822767089345;4613.907721845544;4792.760361981887;4813.95186048142;3674.4855720199193;3676.963230367662;3753.155540003477;3788.242351529705;3792.867249646304;953.7329547726648;1138.7608602193466;1150.3155622404656;1164.6564263542275;1165.0679456434818;586.1169750403576;711.6761873347718;762.4777027735907;763.6105586151103;913.0048247410355;5802.332731735904;5864.296284445893;5962.105684147413;6018.458338749533;6065.848353791102;853.2747164909902;896.878921345623;943.8725772020827;945.921649319776;960.9375390599589;3774.698024158061;3925.568187499373;3931.4692516510477;3941.172353805733;4003.4395184673585;10011.79976400468;10371.585136594602;10412.375257484575;10443.164546836235;10536.631217907336;10570.841437378293;10610.575576976835;10644.787104258026;10652.840207396895;10696;7956.363490920712;8193.631286592117;8219.876701849476;8232.876506852283;8241.093479717227;5857.4435428634215;5866.266716660418;5870.5010074142465;6008.369121242382;6092.086187071975;9305.67430139969;9411.10594493522;9634.988326225646;9646.18007469373;9740.366876153028;9911.355761875744;9967.651132210338;9999.55002024896;10002.899971000488;10003.949980250052;7128.857422851617;7207.9639601801655;7369.410447164107;7377.225391333833;7402.888956665546;7981.321587665114;8184.263416292562;8189.099199087977;8200.589970501333;8202.38482113832;6029.914615369275;7062.435005199674;7084.964575177091;7295.890561641686;7416.44376671741;6961;7055.858882822417;7129.073220481304;7180.5691658500655;7194.208637050025;6694.497912656473;6789.456843452611;6808.455323574003;6813.557118787263;6815.69329380189;4637.675362724577;4723;4777.784999675093;4886.65793394459;4958.752062396794;4813.278008298643;4866.391701037371;4906.190478570996;4997.700137991732;5123.436421993503 02:12:25 INFO - PROCESS | 1671 | |4;prototype.html;275.68434142906375;312.60455523762437;318.7959705788296;319.733022925857;322.64509040055924;4652.906941861161;5067.518585734359;5306.734663266836;5313.468653134684;5370.650907691002;4648.349231107648;4660.627149828014;5295.31160949077;5358.785648574052;5376.1398176291805;3880.2433525462548;3896.7467114637457;4002.679785617144;4101.671866250707;4180.749155050691;3283.4910588858766;3353.144948038259;3706.2402207547525;3813.561440434344;4099.815508302118;3644.6355364463607;3862.304785138674;3894.6494815466604;4042.878713638581;4062.5531191568903;3864.574896761368;3909.9413508797393;3947.269755095288;3956.5054368203973;4038.1923615276914;3151.700588444094;3394.6435624259593;3435.1068722132177;3565.3938830398633;3569.5716514018222;4488.955110448886;4958.87602809929;5167.250748641463;5181.4300426953005;5203.973980130075;4008.579099194572;4481.798319075617;4683.929741053853;4701.200795864711;4725.692829966041;5460.5085542301385;5571.359293681238;5591.720413979285;5596.160575913605;5693.402192769782;3937.606239376068;3951.3875349320615;3953.2884080865433;3954.23203757371;3970.5235371755284;4905.96572101122;5366.973165134188;5763.193152958589;5845.912311315334;5866.266716660418;3970.3647416413237;4009.9599004009588;4011.793513567526;4015.9799201003807;4023.973886658883;3992.4610177626228;4028.8187031583648;4034.636882680573;4053.756774593534;4067.9593204067583;4481.871111222144;4640.308620999812;4674.182018146878;4704.270838020112;4710.222813235846;5082.881766011472;5169.353830771153;5613.775448981995;5689.402612725687;5717.285339332583;1323.9007074469453;1501.8197816262118;1502.9699406011819;1504.5335945856818;1506.7438535449;979.7991411760572;1005.9849102263472;1006.225206590921;1008.7427705935087;1022.3559157730731;4995.701977090506;5193.870153246124;5232.764525596357;5359.196120581866;5373.408925018245;5854.160732044955;5870.970645146747;5873.298282789964;5888.4700376966275;5921.082232254007;4434.378825196876;4775.4212725128045;5220.451852555503;5383.703896285742;5393.325834270716;3358.5465962095295;3393.389189945784;3401.2857299967286;3402.1494626343415;3422.4769977360334;5569.498745112959;6508.5802185508555;6715.623297224058;6728;6754.45964322853;5318.680879147263;5496.642718223303;5957.987142185839;6402.743890244431;6614.603123812587;5636.323641163005;5841.357450680507;6649.933500664932;6668.133142691419;6746;5486.862828429321;5822.7670893163795;6214.2853571838;6299.527535434952;6432.581882177644;6292.685365731603;6311.147995020613;6314.55798094129;6315.76842515705;6345.619262844244;5622.24099746529;6405.519586030973;6684.699188536625;6718.664066796543;6729.69716362755;4262.254105531581;4289.656827453734;4290.978545107255;4298.63461605767;4303.870883873489;5350.892982140257;6161.815145545641;6199.473044791243;6460.773872914426;6483.027545868157;5908.527317814651;6096.957519231108;6238.407351301619;6266.122742815918;6277.18396608438;5164.7417629119145;5706.572007099401;6201.689915504297;6209.782657606962;6222.626642401289;4672.906541869212;5952.8809423812145;6108.884468920794;6121.632702037892;6154.876902461836;5221.556167725786;6060.727267272895;6185.41238582334;6206.224221972254;6229.470495007975;6100.986674397527;6104.877902441837;6126.23422072241;6126.509879209563;6157 02:12:25 INFO - PROCESS | 1671 | |5;yui.html;637.6333608175299;669.8727241824055;711.3810984443531;726.9745558905439;747.7644541969282;14759.852401475982;14761.630959226011;14979.700405991875;15009.774853377181;15019.32413041413;13465.665236051509;14072.22602756848;14073;14298.142111473318;14301.284935753223;898.2634239923266;909.8908131024286;911.9908800911973;916.9128932751378;917.2616044084509;718.3678363040517;743.4535616321999;882.355880207449;904.864270359448;923.5566927874625;939.70399324213;942.6559305853349;942.8538576520616;945.3382632157483;945.877035985321;935.9719208423725;936.0078316984019;940.031767279703;940.9717708468756;941.6845356805478;474.499403129698;692.7852365766629;715.8103102677794;717.9353858152791;717.974870879522;12368.381580920917;14833.110013399231;14871.182084985321;14919.627009324853;14954;12081.456334464881;12168.965637920788;12219.902296726266;12254;12407.25556466615;9303.906960930439;9712.61149554017;9779.381514837552;9852.11330980208;9878.802423951482;3310.5696259486353;3328.3010567780793;3376.5104059911178;3529.1353618363664;3637.0725465006253;68922;69039.65480172617;69180;69231.99999999949;69263.65368173177;8850.20348168668;9362.578683959102;9424.90575094254;9433.811323773487;9752.707418777307;20032.89983550102;20045.697029693023;20071.59856802856;20073.197072116953;20073.397798066082;7196.820079497949;7198.100237470317;7352.669129889167;7364.5581265124265;7405.5926924019695;3907.78507182102;3970.3647416413237;3971;3978.0452691353867;3981.303271927401;3492.161881148557;3524.718022558189;3556.484309775068;3557.48416479609;3558.8042657654078;557.9079451890474;664.6607086720262;691.1982100763091;704.8061783009713;707.7805880177161;474.6274174772787;549.7883314923783;558.5950186115034;601.5368166511762;607.750822162911;7750.844983100419;7807.4925129867515;7971.163027882105;8137.552434616153;8146.869198985923;561.1890817768367;578.4851482180862;579.6116601876753;597.2623809595146;605.4429924469499;3814.427835824593;3831.272058308968;3841.673457756066;3951;4027.291240344241;92909.5354523223;96411;97538;100498.49750751199;102375;77368.61315693386;81171.59414202892;82184;83415.58292208622;85127.29745405057;48913.755431223326;65815.34184658092;66068.00897986437;66527.336816579;68131.65934170298;7445.553266804009;7452.515586486861;7616.276453736886;7619.733309334147;7682.154962954182;38127.04682382962;40944.79527602462;45851.31223031724;47439.460897038865;47819.28271076006;62551.748965021354;64544.354556453836;64985.025224620724;65059;65184;10111.3427627204;10187.898121018696;10337.176591782583;10375.221858360805;10659.360438373722;11041.171912106463;11083.778324433628;11212.383318917538;11315.660530184108;11565.537378504765;10416.739581510523;10497.317674351143;10743.24797264184;10880.619178328721;10919.617813376493;9727.124558789743;10408.427536485264;10607;10714.51784669676;11449.084073274285;10079.647212347532;10197.9490102549;10224.846627300745;10703.892961070602;11091.889081109086;7568.3945284378215;8984.910150898408;9057;9166.08339166078;9189.218916392181;8653.783655408664;8781.604827782638;8812.16284452976;8859.64561417536;9174.403663761834 02:12:25 INFO - PROCESS | 1671 | __end_tp_report 02:12:25 INFO - PROCESS | 1671 | __start_cc_report 02:12:25 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1216 02:12:25 INFO - PROCESS | 1671 | __end_cc_report 02:12:25 INFO - PROCESS | 1671 | __startTimestamp1462007545361__endTimestamp 02:12:25 INFO - PROCESS | 1671 | 02:12:25 INFO - PROCESS | 1671 | ------- Summary: start ------- 02:12:25 INFO - PROCESS | 1671 | Number of tests: 6 02:12:25 INFO - PROCESS | 1671 | 02:12:25 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5747.18 Median:5988.82 stddev:627.37 (10.5%) stddev-sans-first:587.73 02:12:25 INFO - PROCESS | 1671 | Values: 2884.7 3460.4 3487.1 3510.1 3549.1 4696.8 5617.0 5715.7 5727.2 5750.5 5743.5 5852.7 5870.2 5873.2 5874.4 5213.5 5216.5 5234.6 5249.4 5270.8 5121.0 5275.9 5277.2 5290.9 5294.4 5258.7 5286.3 5326.3 5330.5 5337.8 5116.0 5199.1 5202.2 5208.1 5241.4 5158.6 5188.3 5200.4 5217.3 5257.2 5709.3 5722.9 5732.4 5757.4 5792.1 6206.8 6212.9 6218.5 6226.0 6235.0 6062.2 6146.9 6200.2 6251.0 6277.6 5758.8 5778.0 5781.7 5788.7 5804.5 6211.1 6211.5 6242.3 6246.5 6258.2 6087.7 6119.8 6123.5 6138.9 6143.9 6197.4 6215.9 6216.3 6219.7 6235.6 6187.3 6211.3 6226.3 6226.9 6237.3 6107.5 6137.3 6200.4 6203.9 6207.3 4905.9 4910.9 4935.7 4951.5 4954.4 4471.4 4491.2 4558.0 4581.2 4586.3 5765.8 5769.3 5770.2 5778.0 5783.0 6117.8 6157.2 6182.9 6204.3 6229.0 5764.4 5779.5 5845.9 5895.3 5908.4 5421.8 5423.7 5432.7 5436.6 5457.4 5365.8 6164.8 6331.3 6340.6 6351.2 6138.6 6170.7 6206.9 6208.7 6221.3 6302.8 6344.2 6353.8 6364.7 6383.9 5419.8 5988.8 6246.5 6251.3 6264.2 6115.1 6156.9 6196.5 6258.3 6259.4 5461.8 6149.4 6162.6 6164.5 6171.8 6025.8 6166.2 6170.4 6190.3 6190.4 5826.9 5923.8 5944.0 6023.3 6087.2 6158.0 6159.0 6159.0 6165.5 6166.3 5790.2 6007.6 6014.0 6030.6 6039.2 02:12:25 INFO - PROCESS | 1671 | 02:12:25 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:64475.07 Median:19740.79 stddev:136568.96 (691.8%) stddev-sans-first:136867.61 02:12:25 INFO - PROCESS | 1671 | Values: 502.1 509.6 511.2 587.5 593.5 27623.4 31034.4 38031.8 38965.6 39110.4 43008.1 43555.9 43594.6 43601.0 43616.8 9492.9 9772.3 9779.8 9780.8 9802.7 8508.5 8745.7 11416.7 11664.9 11685.2 11614.5 11780.6 11820.5 11831.9 11838.3 8470.1 8702.3 11550.7 11709.4 11732.9 8580.1 8688.7 11357.8 11627.1 11643.4 37908.4 38493.2 38533.7 38572.0 38592.0 3125.1 3277.8 3598.3 3694.8 3769.0 27804.9 28126.0 28135.4 28562.7 28602.6 2694.2 2699.2 2715.4 2807.6 2920.9 351266.0 355362.6 355386.0 355905.0 355934.0 59786.1 60245.7 60747.7 60782.5 60825.0 63735.7 64185.7 64195.7 64295.0 64297.0 16019.5 16323.1 16377.8 16660.9 16942.9 11473.5 11750.9 12536.8 12870.8 12928.3 18820.2 18933.8 18946.0 18956.7 18968.4 6565.7 6845.4 7908.5 8098.3 8138.5 933.3 938.2 972.2 1056.2 1089.0 25582.7 28837.0 29351.9 29548.7 29555.4 1064.7 1071.4 1116.3 1141.0 1212.7 14902.3 15004.7 15281.9 15318.6 15346.5 558849.0 565772.2 567245.0 568799.0 569120.0 49190.5 57260.7 59276.8 59287.4 59287.7 1277.5 1320.1 1443.1 1443.5 1446.9 26607.9 27528.0 29023.0 29679.9 30707.5 50362.0 50555.7 51621.5 51643.0 51643.7 544641.0 591199.0 592121.0 592159.0 592282.0 32794.3 39269.6 39316.4 39844.6 40701.6 44192.7 44214.6 44492.1 45662.5 45898.5 35182.5 37918.9 38178.4 38502.2 39174.4 37644.6 37841.2 37897.6 38681.4 38840.8 34687.3 35261.5 35805.0 35883.3 37303.6 29256.4 31599.4 31835.8 31846.7 32178.8 21160.0 23104.7 23658.5 23729.2 23742.5 02:12:25 INFO - PROCESS | 1671 | 02:12:25 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:34923.94 Median:13523.78 stddev:100570.58 (743.7%) stddev-sans-first:100819.27 02:12:25 INFO - PROCESS | 1671 | Values: 386.4 387.9 390.0 392.9 398.7 19586.6 19972.3 20634.8 20647.9 20678.3 19123.5 19746.1 20984.9 21414.3 21433.8 5797.9 6125.7 6142.6 6147.3 6175.6 5125.2 5993.9 6072.2 6082.9 6084.3 5801.6 5978.6 6006.0 6010.8 6013.8 5894.4 5992.8 6002.0 6004.2 6005.3 5017.2 5167.5 5192.7 5203.9 5205.7 23574.9 23594.9 23860.4 25123.7 26661.2 1861.2 1963.9 2219.1 2320.8 2411.5 9705.4 9797.6 10255.1 11857.8 15135.8 1747.4 1797.1 1805.5 1810.2 1810.7 76810.7 117445.0 125872.0 126905.4 127831.0 9336.0 11359.5 11514.5 11607.1 11714.9 14483.7 14532.2 14564.3 14567.6 14579.9 8468.7 8634.0 8654.6 8748.7 8759.2 8543.4 8556.8 8567.3 8665.7 8693.4 4428.9 4532.3 4538.0 4619.7 4621.8 1634.5 1886.3 1957.0 1958.6 1963.3 667.0 834.9 862.5 907.9 984.8 18935.7 19671.2 19765.5 19921.8 19975.9 749.5 933.9 940.0 967.4 979.9 10325.0 10548.4 10931.9 10952.6 11032.9 596830.0 596985.0 598744.0 609751.0 609915.0 126300.0 126977.7 129961.0 132636.0 133794.0 954.1 957.5 978.7 978.9 1001.9 13450.7 14407.6 17949.0 18290.5 18291.7 50120.5 52294.7 52661.0 53141.2 53865.7 76425.2 77489.0 77884.6 77992.6 78137.2 8001.0 8006.4 8104.4 8283.0 8302.3 7278.5 11164.8 11278.2 12314.6 12885.3 10396.1 10523.5 10779.8 10952.6 11278.6 10415.7 10824.4 10855.2 11130.1 11135.7 10511.8 10845.5 10978.8 11138.7 11215.4 7931.2 7932.6 8182.5 8213.0 8289.7 10102.0 10908.6 11247.6 11259.9 11294.0 02:12:25 INFO - PROCESS | 1671 | 02:12:25 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5557.66 Median:5072.40 stddev:3106.96 (61.3%) stddev-sans-first:3099.74 02:12:25 INFO - PROCESS | 1671 | Values: 1370.8 1544.3 1554.7 1768.8 1918.7 7589.8 8370.9 8567.7 8570.7 8573.5 8208.6 8465.0 8475.0 8479.4 8486.3 2307.8 2349.6 2350.5 2351.2 2378.9 2276.3 2317.8 2321.7 2328.3 2333.4 2294.0 2319.9 2330.2 2340.1 2348.0 2299.1 2318.0 2324.1 2325.7 2335.7 2288.1 2291.9 2293.1 2293.4 2297.4 8447.9 8470.8 8548.0 8554.7 8558.5 5066.7 5252.4 5267.1 5267.4 5276.7 3396.7 3521.6 3582.3 3592.5 3601.4 1009.3 1020.9 1021.5 1028.0 1031.1 11185.0 11311.0 11519.2 11566.7 11569.0 4345.4 4469.1 4469.7 4506.7 4571.9 9055.8 9072.9 9093.1 9111.4 9118.2 5078.1 5143.7 5175.0 5185.6 5188.9 4253.0 4430.8 4613.9 4792.8 4814.0 3674.5 3677.0 3753.2 3788.2 3792.9 953.7 1138.8 1150.3 1164.7 1165.1 586.1 711.7 762.5 763.6 913.0 5802.3 5864.3 5962.1 6018.5 6065.8 853.3 896.9 943.9 945.9 960.9 3774.7 3925.6 3931.5 3941.2 4003.4 10011.8 10371.6 10412.4 10443.2 10536.6 10570.8 10610.6 10644.8 10652.8 10696.0 7956.4 8193.6 8219.9 8232.9 8241.1 5857.4 5866.3 5870.5 6008.4 6092.1 9305.7 9411.1 9635.0 9646.2 9740.4 9911.4 9967.7 9999.6 10002.9 10003.9 7128.9 7208.0 7369.4 7377.2 7402.9 7981.3 8184.3 8189.1 8200.6 8202.4 6029.9 7062.4 7085.0 7295.9 7416.4 6961.0 7055.9 7129.1 7180.6 7194.2 6694.5 6789.5 6808.5 6813.6 6815.7 4637.7 4723.0 4777.8 4886.7 4958.8 4813.3 4866.4 4906.2 4997.7 5123.4 02:12:25 INFO - PROCESS | 1671 | 02:12:25 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4781.23 Median:5198.92 stddev:1523.48 (29.3%) stddev-sans-first:1489.75 02:12:25 INFO - PROCESS | 1671 | Values: 275.7 312.6 318.8 319.7 322.6 4652.9 5067.5 5306.7 5313.5 5370.7 4648.3 4660.6 5295.3 5358.8 5376.1 3880.2 3896.7 4002.7 4101.7 4180.7 3283.5 3353.1 3706.2 3813.6 4099.8 3644.6 3862.3 3894.6 4042.9 4062.6 3864.6 3909.9 3947.3 3956.5 4038.2 3151.7 3394.6 3435.1 3565.4 3569.6 4489.0 4958.9 5167.3 5181.4 5204.0 4008.6 4481.8 4683.9 4701.2 4725.7 5460.5 5571.4 5591.7 5596.2 5693.4 3937.6 3951.4 3953.3 3954.2 3970.5 4906.0 5367.0 5763.2 5845.9 5866.3 3970.4 4010.0 4011.8 4016.0 4024.0 3992.5 4028.8 4034.6 4053.8 4068.0 4481.9 4640.3 4674.2 4704.3 4710.2 5082.9 5169.4 5613.8 5689.4 5717.3 1323.9 1501.8 1503.0 1504.5 1506.7 979.8 1006.0 1006.2 1008.7 1022.4 4995.7 5193.9 5232.8 5359.2 5373.4 5854.2 5871.0 5873.3 5888.5 5921.1 4434.4 4775.4 5220.5 5383.7 5393.3 3358.5 3393.4 3401.3 3402.1 3422.5 5569.5 6508.6 6715.6 6728.0 6754.5 5318.7 5496.6 5958.0 6402.7 6614.6 5636.3 5841.4 6649.9 6668.1 6746.0 5486.9 5822.8 6214.3 6299.5 6432.6 6292.7 6311.1 6314.6 6315.8 6345.6 5622.2 6405.5 6684.7 6718.7 6729.7 4262.3 4289.7 4291.0 4298.6 4303.9 5350.9 6161.8 6199.5 6460.8 6483.0 5908.5 6097.0 6238.4 6266.1 6277.2 5164.7 5706.6 6201.7 6209.8 6222.6 4672.9 5952.9 6108.9 6121.6 6154.9 5221.6 6060.7 6185.4 6206.2 6229.5 6101.0 6104.9 6126.2 6126.5 6157.0 02:12:25 INFO - PROCESS | 1671 | 02:12:25 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17517.49 Median:32590.99 stddev:25073.21 (76.9%) stddev-sans-first:25111.52 02:12:25 INFO - PROCESS | 1671 | Values: 637.6 669.9 711.4 727.0 747.8 14759.9 14761.6 14979.7 15009.8 15019.3 13465.7 14072.2 14073.0 14298.1 14301.3 898.3 909.9 912.0 916.9 917.3 718.4 743.5 882.4 904.9 923.6 939.7 942.7 942.9 945.3 945.9 936.0 936.0 940.0 941.0 941.7 474.5 692.8 715.8 717.9 718.0 12368.4 14833.1 14871.2 14919.6 14954.0 12081.5 12169.0 12219.9 12254.0 12407.3 9303.9 9712.6 9779.4 9852.1 9878.8 3310.6 3328.3 3376.5 3529.1 3637.1 68922.0 69039.7 69180.0 69232.0 69263.7 8850.2 9362.6 9424.9 9433.8 9752.7 20032.9 20045.7 20071.6 20073.2 20073.4 7196.8 7198.1 7352.7 7364.6 7405.6 3907.8 3970.4 3971.0 3978.0 3981.3 3492.2 3524.7 3556.5 3557.5 3558.8 557.9 664.7 691.2 704.8 707.8 474.6 549.8 558.6 601.5 607.8 7750.8 7807.5 7971.2 8137.6 8146.9 561.2 578.5 579.6 597.3 605.4 3814.4 3831.3 3841.7 3951.0 4027.3 92909.5 96411.0 97538.0 100498.5 102375.0 77368.6 81171.6 82184.0 83415.6 85127.3 48913.8 65815.3 66068.0 66527.3 68131.7 7445.6 7452.5 7616.3 7619.7 7682.2 38127.0 40944.8 45851.3 47439.5 47819.3 62551.7 64544.4 64985.0 65059.0 65184.0 10111.3 10187.9 10337.2 10375.2 10659.4 11041.2 11083.8 11212.4 11315.7 11565.5 10416.7 10497.3 10743.2 10880.6 10919.6 9727.1 10408.4 10607.0 10714.5 11449.1 10079.6 10197.9 10224.8 10703.9 11091.9 7568.4 8984.9 9057.0 9166.1 9189.2 8653.8 8781.6 8812.2 8859.6 9174.4 02:12:25 INFO - PROCESS | 1671 | -------- Summary: end -------- 02:12:25 INFO - PROCESS | 1671 | 02:12:25 INFO - TEST-INFO | 1671: exit 0 02:12:25 INFO - TEST-OK | dromaeo_css | took 1115943ms 02:12:25 INFO - TEST-START | kraken 02:12:25 INFO - operating with platform_type : mac_ 02:12:25 INFO - Initialising browser for kraken test... 02:12:25 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpL6X826/profile http://localhost:49281/getInfo.html) 02:12:28 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 02:12:28 INFO - PROCESS | 1676 | colorDepth:24 02:12:28 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 02:12:28 INFO - PROCESS | 1676 | __metrics 02:12:28 INFO - PROCESS | 1676 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:12:28 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:12:29 INFO - TEST-INFO | 1676: exit 0 02:12:29 INFO - Browser initialized. 02:12:29 INFO - Running cycle 1/1 for kraken test... 02:12:29 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpL6X826/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:12:41 INFO - PROCESS | 1678 | RSS: Main: 239607808 02:12:41 INFO - PROCESS | 1678 | 02:12:43 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 02:12:44 INFO - PROCESS | 1678 | RSS: Main: 286879744 02:12:44 INFO - PROCESS | 1678 | 02:12:47 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 02:12:47 INFO - PROCESS | 1678 | RSS: Main: 295563264 02:12:47 INFO - PROCESS | 1678 | 02:12:51 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 02:12:52 INFO - PROCESS | 1678 | RSS: Main: 377835520 02:12:52 INFO - PROCESS | 1678 | 02:12:54 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 02:12:54 INFO - PROCESS | 1678 | RSS: Main: 280158208 02:12:54 INFO - PROCESS | 1678 | 02:12:57 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:12:57 INFO - PROCESS | 1678 | RSS: Main: 284819456 02:12:57 INFO - PROCESS | 1678 | 02:13:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 02:13:01 INFO - PROCESS | 1678 | RSS: Main: 323903488 02:13:01 INFO - PROCESS | 1678 | 02:13:04 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 02:13:04 INFO - PROCESS | 1678 | RSS: Main: 324132864 02:13:04 INFO - PROCESS | 1678 | 02:13:07 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 02:13:08 INFO - PROCESS | 1678 | RSS: Main: 320442368 02:13:08 INFO - PROCESS | 1678 | 02:13:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:13:10 INFO - PROCESS | 1678 | RSS: Main: 312881152 02:13:10 INFO - PROCESS | 1678 | 02:13:12 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:13:12 INFO - PROCESS | 1678 | RSS: Main: 319582208 02:13:12 INFO - PROCESS | 1678 | 02:13:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:13:15 INFO - PROCESS | 1678 | RSS: Main: 321589248 02:13:15 INFO - PROCESS | 1678 | 02:13:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:13:19 INFO - PROCESS | 1678 | RSS: Main: 319221760 02:13:19 INFO - PROCESS | 1678 | 02:13:22 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:13:22 INFO - PROCESS | 1678 | RSS: Main: 319823872 02:13:22 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 02:13:24 INFO - PROCESS | 1678 | RSS: Main: 384004096 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | __start_tp_report 02:13:24 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 02:13:24 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 02:13:24 INFO - PROCESS | 1678 | |i|pagename|runs| 02:13:24 INFO - PROCESS | 1678 | |0;ai-astar;117;113;107;113;113;113;114;114;104;115 02:13:24 INFO - PROCESS | 1678 | |1;audio-beat-detection;160;115;144;165;121;140;140;120;173;121 02:13:24 INFO - PROCESS | 1678 | |2;audio-dft;186;186;183;184;186;249;266;188;183;182 02:13:24 INFO - PROCESS | 1678 | |3;audio-fft;80;76;90;92;60;61;61;82;94;74 02:13:24 INFO - PROCESS | 1678 | |4;audio-oscillator;94;89;132;77;81;83;121;110;111;158 02:13:24 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;87;85;87;85;89;129;124;86;89;84 02:13:24 INFO - PROCESS | 1678 | |6;imaging-darkroom;93;93;94;94;93;94;138;149;95;93 02:13:24 INFO - PROCESS | 1678 | |7;imaging-desaturate;84;84;86;84;85;83;86;84;86;83 02:13:24 INFO - PROCESS | 1678 | |8;json-parse-financial;60;61;62;60;59;58;60;79;60;57 02:13:24 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;64;65;75;76;70;69;70;69;70;68 02:13:24 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;84;83;84;84;83;84;82;83;84;85 02:13:24 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;152;152;136;124;149;128;147;147;195;151 02:13:24 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;171;175;227;197;171;172;169;171;171;167 02:13:24 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;69;68;70;80;68;70;70;67;66;63 02:13:24 INFO - PROCESS | 1678 | __end_tp_report 02:13:24 INFO - PROCESS | 1678 | __start_cc_report 02:13:24 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1998 02:13:24 INFO - PROCESS | 1678 | __end_cc_report 02:13:24 INFO - PROCESS | 1678 | __startTimestamp1462007604750__endTimestamp 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | ------- Summary: start ------- 02:13:24 INFO - PROCESS | 1678 | Number of tests: 14 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.50 stddev:3.86 (3.4%) stddev-sans-first:3.70 02:13:24 INFO - PROCESS | 1678 | Values: 117.0 113.0 107.0 113.0 113.0 113.0 114.0 114.0 104.0 115.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:142.00 stddev:20.75 (14.6%) stddev-sans-first:20.70 02:13:24 INFO - PROCESS | 1678 | Values: 160.0 115.0 144.0 165.0 121.0 140.0 140.0 120.0 173.0 121.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:199.30 Median:186.00 stddev:30.99 (16.7%) stddev-sans-first:32.49 02:13:24 INFO - PROCESS | 1678 | Values: 186.0 186.0 183.0 184.0 186.0 249.0 266.0 188.0 183.0 182.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:77.00 Median:81.00 stddev:13.03 (16.1%) stddev-sans-first:13.77 02:13:24 INFO - PROCESS | 1678 | Values: 80.0 76.0 90.0 92.0 60.0 61.0 61.0 82.0 94.0 74.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:105.60 Median:79.00 stddev:25.98 (32.9%) stddev-sans-first:27.21 02:13:24 INFO - PROCESS | 1678 | Values: 94.0 89.0 132.0 77.0 81.0 83.0 121.0 110.0 111.0 158.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:86.50 stddev:16.99 (19.6%) stddev-sans-first:17.80 02:13:24 INFO - PROCESS | 1678 | Values: 87.0 85.0 87.0 85.0 89.0 129.0 124.0 86.0 89.0 84.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:93.50 stddev:21.20 (22.7%) stddev-sans-first:22.13 02:13:24 INFO - PROCESS | 1678 | Values: 93.0 93.0 94.0 94.0 93.0 94.0 138.0 149.0 95.0 93.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:84.50 stddev:1.18 (1.4%) stddev-sans-first:1.24 02:13:24 INFO - PROCESS | 1678 | Values: 84.0 84.0 86.0 84.0 85.0 83.0 86.0 84.0 86.0 83.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:60.00 stddev:6.28 (10.5%) stddev-sans-first:6.63 02:13:24 INFO - PROCESS | 1678 | Values: 60.0 61.0 62.0 60.0 59.0 58.0 60.0 79.0 60.0 57.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:3.75 (5.4%) stddev-sans-first:3.38 02:13:24 INFO - PROCESS | 1678 | Values: 64.0 65.0 75.0 76.0 70.0 69.0 70.0 69.0 70.0 68.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:0.84 (1.0%) stddev-sans-first:0.88 02:13:24 INFO - PROCESS | 1678 | Values: 84.0 83.0 84.0 84.0 83.0 84.0 82.0 83.0 84.0 85.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:150.00 stddev:19.36 (12.9%) stddev-sans-first:20.48 02:13:24 INFO - PROCESS | 1678 | Values: 152.0 152.0 136.0 124.0 149.0 128.0 147.0 147.0 195.0 151.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:171.50 stddev:18.84 (11.0%) stddev-sans-first:19.75 02:13:24 INFO - PROCESS | 1678 | Values: 171.0 175.0 227.0 197.0 171.0 172.0 169.0 171.0 171.0 167.0 02:13:24 INFO - PROCESS | 1678 | 02:13:24 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.50 stddev:4.41 (6.3%) stddev-sans-first:4.68 02:13:24 INFO - PROCESS | 1678 | Values: 69.0 68.0 70.0 80.0 68.0 70.0 70.0 67.0 66.0 63.0 02:13:24 INFO - PROCESS | 1678 | -------- Summary: end -------- 02:13:24 INFO - PROCESS | 1678 | 02:13:25 INFO - TEST-INFO | 1678: exit 0 02:13:25 INFO - TEST-OK | kraken | took 59571ms 02:13:25 INFO - SUITE-END | took 1175s 02:13:25 INFO - Completed test suite (00:19:35) 02:13:25 INFO - javascript benchmark 02:13:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2884.7115288471155, 3460.394430974579, 3487.0584942065643, 3510.1400156961517, 3549.148204430936, 4696.788644510997, 5617.0, 5715.657060576368, 5727.169560413738, 5750.453706897849, 5743.54051675866, 5852.736626851792, 5870.236869207008, 5873.236479257691, 5874.412558744123, 5213.504717051893, 5216.504432078966, 5234.555062819665, 5249.422563518029, 5270.762815673284, 5121.0, 5275.86810329741, 5277.182036784286, 5290.894182116355, 5294.444083371249, 5258.684478931276, 5286.3392075990505, 5326.280952071459, 5330.466953304677, 5337.7598008089535, 5116.002379535992, 5199.14214154664, 5202.2456743771945, 5208.062548741225, 5241.397239317451, 5158.561522270612, 5188.273641690166, 5200.3759548854005, 5217.269582258449, 5257.185136303879, 5709.286339207599, 5722.885542289173, 5732.398098199671, 5757.395473475267, 5792.102224155263, 6206.782762603332, 6212.906806397862, 6218.4714299284615, 6225.968870155665, 6234.9688251558455, 6062.151298818169, 6146.89355915935, 6200.224971878515, 6250.96874515629, 6277.560570760049, 5758.7696492140685, 5778.0, 5781.739821708034, 5788.6526808392055, 5804.506616937565, 6211.130441738161, 6211.503079753609, 6242.28213755424, 6246.500279977591, 6258.217722784652, 6087.6956152192215, 6119.84700382494, 6123.4795042421, 6138.878900475427, 6143.877122457526, 6197.442230199304, 6215.875682486325, 6216.2540495140875, 6219.657918814418, 6235.594686345373, 6187.319394866561, 6211.3478084800445, 6226.315105338409, 6226.910290699109, 6237.282712488122, 6107.492261292593, 6137.263528376623, 6200.3799620037635, 6203.937960620427, 6207.317195108534, 4905.877353066202, 4910.92633610489, 4935.728534930541, 4951.5048495150195, 4954.380702412199, 4471.3516540102155, 4491.1915855145735, 4557.977210113994, 4581.221192397301, 4586.334981427674, 5765.769369225268, 5769.307683078057, 5770.24986751728, 5777.971110144506, 5782.971085144632, 6117.827404800917, 6157.230346206724, 6182.876342473215, 6204.255489341218, 6229.0, 5764.365919748824, 5779.508741756914, 5845.941540584625, 5895.322037965689, 5908.438698323653, 5421.83734487966, 5423.674579525241, 5432.728363581884, 5436.592255580768, 5457.426970168075, 5365.812196573102, 6164.845878853064, 6331.3352098029045, 6340.556161068866, 6351.2060992375955, 6138.6336396323695, 6170.6914654268, 6206.906896396467, 6208.72060757258, 6221.3156552780065, 6302.810915672537, 6344.238691357066, 6353.809385718436, 6364.713587888648, 6383.872322553616, 5419.756110975094, 5988.820335389945, 6246.500279977682, 6251.343608920998, 6264.185655864709, 6115.052166914135, 6156.876862462636, 6196.504279657526, 6258.342873998165, 6259.436650701458, 5461.808836690697, 6149.354317796567, 6162.630242185483, 6164.506839452923, 6171.84570385744, 6025.849353766191, 6166.229221347332, 6170.352113028068, 6190.3190649029475, 6190.442860142609, 5826.854328641818, 5923.763049477972, 5944.0, 6023.266951350927, 6087.178230938766, 6157.96921015392, 6158.969205154125, 6158.983767678283, 6165.5375846810775, 6166.321704612579, 5790.1893734877995, 6007.639541627516, 6013.9699301503215, 6030.5778595497895, 6039.184710064085], "value": 5708.263019985899, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.11627535537446, 509.5821426430326, 511.2433598274554, 587.4947545111205, 593.4955288005193, 27623.447531049365, 31034.379312413737, 38031.80984095079, 38965.61034389655, 39110.41334379987, 43008.139837203234, 43555.91110222246, 43594.56405435945, 43601.0, 43616.781916090375, 9492.905070949306, 9772.267079968995, 9779.755506112333, 9780.804383912353, 9802.705918822447, 8508.489490630582, 8745.693900713477, 11416.71458213543, 11664.941675291611, 11685.240459370156, 11614.535418583246, 11780.646580602595, 11820.527178912833, 11831.940840295829, 11838.289702617827, 8470.110638383005, 8702.30381569473, 11550.653480395602, 11709.414529273545, 11732.882671173264, 8580.099089595566, 8688.739337819876, 11357.829632555518, 11627.12796540263, 11643.41782910851, 37908.43137352942, 38493.23013539742, 38533.68797872137, 38572.035699107466, 38592.0, 3125.1249650098007, 3277.8197199154038, 3598.298331825295, 3694.8152592370543, 3768.9811550942068, 27804.860975695196, 28126.0, 28135.43729125406, 28562.714372856215, 28602.570961435595, 2694.151342327161, 2699.1632593895956, 2715.375463643373, 2807.6490438695164, 2920.8977685780897, 351266.0, 355362.57099431986, 355386.0, 355905.0, 355934.0, 59786.10320845191, 60245.69877150674, 60747.69626151841, 60782.48043798852, 60825.0, 63735.68132159402, 64185.67907160434, 64195.67902160553, 64295.0, 64297.0, 16019.519414417586, 16323.10222937726, 16377.754333685005, 16660.916695416447, 16942.91528542374, 11473.483693233658, 11750.882491174978, 12536.81194782079, 12870.806937895939, 12928.288944107977, 18820.247190112517, 18933.81066189348, 18946.0, 18956.715649265272, 18968.43094707161, 6565.704543295563, 6845.418139458102, 7908.5254884705955, 8098.271155596025, 8138.470999385021, 933.2673851026912, 938.2212763406357, 972.2125078686287, 1056.2395075545594, 1088.9891101089047, 25582.744172558407, 28837.0, 29351.932866688032, 29548.704512955024, 29555.408891822473, 1064.6965614799744, 1071.3678929431674, 1116.3134672176552, 1141.0244241173843, 1212.678640160358, 14902.316675476011, 15004.699906002037, 15281.923590381977, 15318.617034574225, 15346.462873799363, 558849.0, 565772.1711391417, 567245.0, 568799.0, 569120.0, 49190.508094918594, 57260.71369643125, 59276.814463711344, 59287.40712592819, 59287.70356148192, 1277.5337001994205, 1320.1155225998796, 1443.1124858212333, 1443.4947768281017, 1446.9493567725078, 26607.866960665073, 27528.03651872175, 29023.0, 29679.85160074186, 30707.539386909662, 50361.99276014386, 50555.74722126366, 51621.48378516317, 51643.0, 51643.74178129085, 544641.0, 591199.0, 592121.0, 592159.0, 592282.0, 32794.34411311713, 39269.6073039266, 39316.410253846785, 39844.60155398409, 40701.557906525704, 44192.67421977346, 44214.55785442233, 44492.11015779731, 45662.543374567154, 45898.54101458943, 35182.47226291659, 37918.94348820719, 38178.42732358961, 38502.22995540129, 39174.412383813695, 37644.62355376521, 37841.24317513689, 37897.62102378941, 38681.41977870278, 38840.80579597084, 34687.306253875286, 35261.47107793334, 35805.0, 35883.28233435265, 37303.626963730014, 29256.414871702873, 31599.36801264008, 31835.840820795747, 31846.6815331853, 32178.83910580526, 21160.047797849442, 23104.653430198898, 23658.52682946366, 23729.16947906815, 23742.525149497258], "value": 19854.28506911586, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.36635917095964, 387.85649309755377, 389.95710471848093, 392.88134106097453, 398.6591464298025, 19586.608267834672, 19972.300969466072, 20634.793652063476, 20647.896760516196, 20678.276260330873, 19123.521911952208, 19746.111424985873, 20984.8950755246, 21414.25050123246, 21433.785662143375, 5797.855053623662, 6125.724342404603, 6142.600730952474, 6147.262328520584, 6175.567710260284, 5125.205593133071, 5993.88012239755, 6072.2106126203535, 6082.908756368658, 6084.330723620398, 5801.564882633798, 5978.551608629348, 6005.969970150165, 6010.819675409744, 6013.819585412422, 5894.3810899855325, 5992.760289588411, 6002.0, 6004.159417681528, 6005.309389420229, 5017.197248440232, 5167.509086636764, 5192.7403629818355, 5203.907179492311, 5205.713685747282, 23574.88212558926, 23594.882025589934, 23860.40348991272, 25123.748762512507, 26661.200163995112, 1861.171778558542, 1963.8919859407727, 2219.079082180893, 2320.7795259450327, 2411.4574220800355, 9705.369151005161, 9797.559109840075, 10255.128314093312, 11857.822132668018, 15135.772963405558, 1747.4146161035942, 1797.1104303369784, 1805.4673871207895, 1810.2216047099741, 1810.6650269700017, 76810.69567912971, 117445.0, 125872.0, 126905.36547317296, 127831.0, 9336.0, 11359.545618175345, 11514.53941842317, 11607.071434285237, 11714.88285117155, 14483.710325793425, 14532.200728959797, 14564.344604492824, 14567.562973110824, 14579.854201458062, 8468.703595374132, 8633.956830215935, 8654.610542525601, 8748.69379571712, 8759.16787905148, 8543.444676096158, 8556.786080348042, 8567.27178189861, 8665.696700615574, 8693.391462597558, 4428.933565996513, 4532.297493888453, 4538.0, 4619.745913974696, 4621.815127394867, 1634.525987463646, 1886.2643569008098, 1956.9608607827763, 1958.5691147947714, 1963.3226536844766, 666.9733210671616, 834.9332053435832, 862.4954401674964, 907.9118028534476, 984.7833476635129, 18935.715964260547, 19671.21315147378, 19765.505862353555, 19921.800781992286, 19975.900120499304, 749.5465243527697, 933.939293945905, 939.9718008459757, 967.3615413826981, 979.8971108033696, 10325.0, 10548.41983690889, 10931.945340273247, 10952.616658416917, 11032.944835275772, 596830.0, 596985.0, 598744.0062799659, 609751.0, 609915.0, 126300.0, 126977.73022269843, 129961.0, 132636.0, 133793.99309010373, 954.1079091049891, 957.4877440569401, 978.7014960436993, 978.8727465429448, 1001.8597396364515, 13450.663733406744, 14407.639809004859, 17949.0, 18290.451286461426, 18291.725624115916, 50120.49879501158, 52294.73852630713, 52661.0, 53141.20288195603, 53865.73067134639, 76425.23574764181, 77489.0, 77884.61057694675, 77992.61003694945, 78137.218627813, 8001.0, 8006.399520036137, 8104.432689711663, 8283.006039275326, 8302.252797248277, 7278.454115941219, 11164.776704465703, 11278.210525263154, 12314.568990085663, 12885.291308978096, 10396.116330112025, 10523.526441310007, 10779.838302425627, 10952.561897524329, 11278.60524881658, 10415.68752937413, 10824.4046577439, 10855.18586106061, 11130.10959123252, 11135.72160695989, 10511.789764204826, 10845.457727113771, 10978.780424391307, 11138.721531961442, 11215.383153926612, 7931.2068793120225, 7932.6430310635005, 8182.4681395709085, 8213.014438267446, 8289.709860154866, 10102.0, 10908.563657453613, 11247.606333778278, 11259.887401125883, 11294.0], "value": 9423.07101849673, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.7601169795296, 1544.2741911301687, 1554.7046061248361, 1768.8231176882305, 1918.7217853411246, 7589.848203035936, 8370.874436883443, 8567.65729370826, 8570.65717371306, 8573.52845593493, 8208.589570521464, 8464.957675211615, 8474.95762521188, 8479.448835825682, 8486.321094312456, 2307.769223077687, 2349.5770761262966, 2350.518143780521, 2351.247600767755, 2378.8691621960784, 2276.3171048685413, 2317.8029867461287, 2321.663358812971, 2328.2654422455294, 2333.369990102671, 2293.954120917589, 2319.895604697784, 2330.161141988891, 2340.0873659272897, 2347.9882600586975, 2299.1033496936207, 2318.0, 2324.1052194904914, 2325.7209134903837, 2335.6730057791924, 2288.1305104060352, 2291.9312420627243, 2293.0942277800177, 2293.380787187467, 2297.437127903674, 8447.915520844774, 8470.788230294293, 8547.957260213721, 8554.700585479479, 8558.486490810572, 5066.670666406709, 5252.395974462947, 5267.104592219293, 5267.3679158500845, 5276.709780962045, 3396.694297513211, 3521.630228825962, 3582.283543291326, 3592.515010473579, 3601.3877640801124, 1009.2783659683362, 1020.9489525523768, 1021.4688362051766, 1027.9640212592599, 1031.0617338222182, 11185.0, 11311.0, 11519.193656444053, 11566.710832229177, 11569.01663358624, 4345.435093437833, 4469.128519938581, 4469.687121901436, 4506.662000349987, 4571.862844114682, 9055.81888362229, 9072.90927090734, 9093.090690930987, 9111.362204645611, 9118.224950879117, 5078.13671675816, 5143.691378517301, 5175.0, 5185.585153187736, 5188.870278242998, 4252.957470425318, 4430.822767089345, 4613.907721845544, 4792.760361981887, 4813.95186048142, 3674.4855720199193, 3676.963230367662, 3753.155540003477, 3788.242351529705, 3792.867249646304, 953.7329547726648, 1138.7608602193466, 1150.3155622404656, 1164.6564263542275, 1165.0679456434818, 586.1169750403576, 711.6761873347718, 762.4777027735907, 763.6105586151103, 913.0048247410355, 5802.332731735904, 5864.296284445893, 5962.105684147413, 6018.458338749533, 6065.848353791102, 853.2747164909902, 896.878921345623, 943.8725772020827, 945.921649319776, 960.9375390599589, 3774.698024158061, 3925.568187499373, 3931.4692516510477, 3941.172353805733, 4003.4395184673585, 10011.79976400468, 10371.585136594602, 10412.375257484575, 10443.164546836235, 10536.631217907336, 10570.841437378293, 10610.575576976835, 10644.787104258026, 10652.840207396895, 10696.0, 7956.363490920712, 8193.631286592117, 8219.876701849476, 8232.876506852283, 8241.093479717227, 5857.4435428634215, 5866.266716660418, 5870.5010074142465, 6008.369121242382, 6092.086187071975, 9305.67430139969, 9411.10594493522, 9634.988326225646, 9646.18007469373, 9740.366876153028, 9911.355761875744, 9967.651132210338, 9999.55002024896, 10002.899971000488, 10003.949980250052, 7128.857422851617, 7207.9639601801655, 7369.410447164107, 7377.225391333833, 7402.888956665546, 7981.321587665114, 8184.263416292562, 8189.099199087977, 8200.589970501333, 8202.38482113832, 6029.914615369275, 7062.435005199674, 7084.964575177091, 7295.890561641686, 7416.44376671741, 6961.0, 7055.858882822417, 7129.073220481304, 7180.5691658500655, 7194.208637050025, 6694.497912656473, 6789.456843452611, 6808.455323574003, 6813.557118787263, 6815.69329380189, 4637.675362724577, 4723.0, 4777.784999675093, 4886.65793394459, 4958.752062396794, 4813.278008298643, 4866.391701037371, 4906.190478570996, 4997.700137991732, 5123.436421993503], "value": 4450.19275776304, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.68434142906375, 312.60455523762437, 318.7959705788296, 319.733022925857, 322.64509040055924, 4652.906941861161, 5067.518585734359, 5306.734663266836, 5313.468653134684, 5370.650907691002, 4648.349231107648, 4660.627149828014, 5295.31160949077, 5358.785648574052, 5376.1398176291805, 3880.2433525462548, 3896.7467114637457, 4002.679785617144, 4101.671866250707, 4180.749155050691, 3283.4910588858766, 3353.144948038259, 3706.2402207547525, 3813.561440434344, 4099.815508302118, 3644.6355364463607, 3862.304785138674, 3894.6494815466604, 4042.878713638581, 4062.5531191568903, 3864.574896761368, 3909.9413508797393, 3947.269755095288, 3956.5054368203973, 4038.1923615276914, 3151.700588444094, 3394.6435624259593, 3435.1068722132177, 3565.3938830398633, 3569.5716514018222, 4488.955110448886, 4958.87602809929, 5167.250748641463, 5181.4300426953005, 5203.973980130075, 4008.579099194572, 4481.798319075617, 4683.929741053853, 4701.200795864711, 4725.692829966041, 5460.5085542301385, 5571.359293681238, 5591.720413979285, 5596.160575913605, 5693.402192769782, 3937.606239376068, 3951.3875349320615, 3953.2884080865433, 3954.23203757371, 3970.5235371755284, 4905.96572101122, 5366.973165134188, 5763.193152958589, 5845.912311315334, 5866.266716660418, 3970.3647416413237, 4009.9599004009588, 4011.793513567526, 4015.9799201003807, 4023.973886658883, 3992.4610177626228, 4028.8187031583648, 4034.636882680573, 4053.756774593534, 4067.9593204067583, 4481.871111222144, 4640.308620999812, 4674.182018146878, 4704.270838020112, 4710.222813235846, 5082.881766011472, 5169.353830771153, 5613.775448981995, 5689.402612725687, 5717.285339332583, 1323.9007074469453, 1501.8197816262118, 1502.9699406011819, 1504.5335945856818, 1506.7438535449, 979.7991411760572, 1005.9849102263472, 1006.225206590921, 1008.7427705935087, 1022.3559157730731, 4995.701977090506, 5193.870153246124, 5232.764525596357, 5359.196120581866, 5373.408925018245, 5854.160732044955, 5870.970645146747, 5873.298282789964, 5888.4700376966275, 5921.082232254007, 4434.378825196876, 4775.4212725128045, 5220.451852555503, 5383.703896285742, 5393.325834270716, 3358.5465962095295, 3393.389189945784, 3401.2857299967286, 3402.1494626343415, 3422.4769977360334, 5569.498745112959, 6508.5802185508555, 6715.623297224058, 6728.0, 6754.45964322853, 5318.680879147263, 5496.642718223303, 5957.987142185839, 6402.743890244431, 6614.603123812587, 5636.323641163005, 5841.357450680507, 6649.933500664932, 6668.133142691419, 6746.0, 5486.862828429321, 5822.7670893163795, 6214.2853571838, 6299.527535434952, 6432.581882177644, 6292.685365731603, 6311.147995020613, 6314.55798094129, 6315.76842515705, 6345.619262844244, 5622.24099746529, 6405.519586030973, 6684.699188536625, 6718.664066796543, 6729.69716362755, 4262.254105531581, 4289.656827453734, 4290.978545107255, 4298.63461605767, 4303.870883873489, 5350.892982140257, 6161.815145545641, 6199.473044791243, 6460.773872914426, 6483.027545868157, 5908.527317814651, 6096.957519231108, 6238.407351301619, 6266.122742815918, 6277.18396608438, 5164.7417629119145, 5706.572007099401, 6201.689915504297, 6209.782657606962, 6222.626642401289, 4672.906541869212, 5952.8809423812145, 6108.884468920794, 6121.632702037892, 6154.876902461836, 5221.556167725786, 6060.727267272895, 6185.41238582334, 6206.224221972254, 6229.470495007975, 6100.986674397527, 6104.877902441837, 6126.23422072241, 6126.509879209563, 6157.0], "value": 4305.1898717835265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.6333608175299, 669.8727241824055, 711.3810984443531, 726.9745558905439, 747.7644541969282, 14759.852401475982, 14761.630959226011, 14979.700405991875, 15009.774853377181, 15019.32413041413, 13465.665236051509, 14072.22602756848, 14073.0, 14298.142111473318, 14301.284935753223, 898.2634239923266, 909.8908131024286, 911.9908800911973, 916.9128932751378, 917.2616044084509, 718.3678363040517, 743.4535616321999, 882.355880207449, 904.864270359448, 923.5566927874625, 939.70399324213, 942.6559305853349, 942.8538576520616, 945.3382632157483, 945.877035985321, 935.9719208423725, 936.0078316984019, 940.031767279703, 940.9717708468756, 941.6845356805478, 474.499403129698, 692.7852365766629, 715.8103102677794, 717.9353858152791, 717.974870879522, 12368.381580920917, 14833.110013399231, 14871.182084985321, 14919.627009324853, 14954.0, 12081.456334464881, 12168.965637920788, 12219.902296726266, 12254.0, 12407.25556466615, 9303.906960930439, 9712.61149554017, 9779.381514837552, 9852.11330980208, 9878.802423951482, 3310.5696259486353, 3328.3010567780793, 3376.5104059911178, 3529.1353618363664, 3637.0725465006253, 68922.0, 69039.65480172617, 69180.0, 69231.99999999949, 69263.65368173177, 8850.20348168668, 9362.578683959102, 9424.90575094254, 9433.811323773487, 9752.707418777307, 20032.89983550102, 20045.697029693023, 20071.59856802856, 20073.197072116953, 20073.397798066082, 7196.820079497949, 7198.100237470317, 7352.669129889167, 7364.5581265124265, 7405.5926924019695, 3907.78507182102, 3970.3647416413237, 3971.0, 3978.0452691353867, 3981.303271927401, 3492.161881148557, 3524.718022558189, 3556.484309775068, 3557.48416479609, 3558.8042657654078, 557.9079451890474, 664.6607086720262, 691.1982100763091, 704.8061783009713, 707.7805880177161, 474.6274174772787, 549.7883314923783, 558.5950186115034, 601.5368166511762, 607.750822162911, 7750.844983100419, 7807.4925129867515, 7971.163027882105, 8137.552434616153, 8146.869198985923, 561.1890817768367, 578.4851482180862, 579.6116601876753, 597.2623809595146, 605.4429924469499, 3814.427835824593, 3831.272058308968, 3841.673457756066, 3951.0, 4027.291240344241, 92909.5354523223, 96411.0, 97538.0, 100498.49750751199, 102375.0, 77368.61315693386, 81171.59414202892, 82184.0, 83415.58292208622, 85127.29745405057, 48913.755431223326, 65815.34184658092, 66068.00897986437, 66527.336816579, 68131.65934170298, 7445.553266804009, 7452.515586486861, 7616.276453736886, 7619.733309334147, 7682.154962954182, 38127.04682382962, 40944.79527602462, 45851.31223031724, 47439.460897038865, 47819.28271076006, 62551.748965021354, 64544.354556453836, 64985.025224620724, 65059.0, 65184.0, 10111.3427627204, 10187.898121018696, 10337.176591782583, 10375.221858360805, 10659.360438373722, 11041.171912106463, 11083.778324433628, 11212.383318917538, 11315.660530184108, 11565.537378504765, 10416.739581510523, 10497.317674351143, 10743.24797264184, 10880.619178328721, 10919.617813376493, 9727.124558789743, 10408.427536485264, 10607.0, 10714.51784669676, 11449.084073274285, 10079.647212347532, 10197.9490102549, 10224.846627300745, 10703.892961070602, 11091.889081109086, 7568.3945284378215, 8984.910150898408, 9057.0, 9166.08339166078, 9189.218916392181, 8653.783655408664, 8781.604827782638, 8812.16284452976, 8859.64561417536, 9174.403663761834], "value": 6643.031730577917, "unit": "score"}], "name": "dromaeo_css", "value": 7170.599040652493, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 107.0, 113.0, 113.0, 113.0, 114.0, 114.0, 104.0, 115.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 115.0, 144.0, 165.0, 121.0, 140.0, 140.0, 120.0, 173.0, 121.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 183.0, 184.0, 186.0, 249.0, 266.0, 188.0, 183.0, 182.0], "value": 199.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 90.0, 92.0, 60.0, 61.0, 61.0, 82.0, 94.0, 74.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 89.0, 132.0, 77.0, 81.0, 83.0, 121.0, 110.0, 111.0, 158.0], "value": 105.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 89.0, 129.0, 124.0, 86.0, 89.0, 84.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 94.0, 138.0, 149.0, 95.0, 93.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 84.0, 85.0, 83.0, 86.0, 84.0, 86.0, 83.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 62.0, 60.0, 59.0, 58.0, 60.0, 79.0, 60.0, 57.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 75.0, 76.0, 70.0, 69.0, 70.0, 69.0, 70.0, 68.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 84.0, 83.0, 84.0, 82.0, 83.0, 84.0, 85.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 136.0, 124.0, 149.0, 128.0, 147.0, 147.0, 195.0, 151.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 175.0, 227.0, 197.0, 171.0, 172.0, 169.0, 171.0, 171.0, 167.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 70.0, 80.0, 68.0, 70.0, 70.0, 67.0, 66.0, 63.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1527.7999999999997, "alertThreshold": 2.0}]} 02:13:26 INFO - Return code: 0 02:13:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:13:26 ERROR - # TBPL SUCCESS # 02:13:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:13:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:13:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:13:26 INFO - 2016-04-30 02:13:26.075 screenresolution[1682:10423] starting screenresolution argv=screenresolution get 02:13:26 INFO - 2016-04-30 02:13:26.078 screenresolution[1682:10423] Display 0: 1600x1200x32@60 02:13:26 INFO - 2016-04-30 02:13:26.084 screenresolution[1683:10426] starting screenresolution argv=screenresolution list 02:13:26 INFO - Available Modes on Display 0 02:13:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:13:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:13:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:13:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:13:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:13:26 INFO - Intel Iris: 02:13:26 INFO - Chipset Model: Intel Iris 02:13:26 INFO - Type: GPU 02:13:26 INFO - Bus: Built-In 02:13:26 INFO - VRAM (Dynamic, Max): 1536 MB 02:13:26 INFO - Vendor: Intel (0x8086) 02:13:26 INFO - Device ID: 0x0a2e 02:13:26 INFO - Revision ID: 0x0009 02:13:26 INFO - Displays: 02:13:26 INFO - Display: 02:13:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:13:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:13:26 INFO - Display Serial Number: 1600x1200 60 02:13:26 INFO - Main Display: Yes 02:13:26 INFO - Mirror: Off 02:13:26 INFO - Online: Yes 02:13:26 INFO - Rotation: Supported 02:13:26 INFO - Return code: 0 02:13:26 INFO - Running post-action listener: _resource_record_post_action 02:13:26 INFO - Running post-run listener: _resource_record_post_run 02:13:27 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32333312; Write bytes: 697635840; Read time: 323; Write time: 1893 02:13:27 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 379181568; Write bytes: 373568000; Read time: 28242; Write time: 699 02:13:27 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 31395328; Write bytes: 295871488; Read time: 309; Write time: 1107 02:13:27 INFO - Running post-run listener: _upload_blobber_files 02:13:27 INFO - Blob upload gear active. 02:13:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:13:27 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 02:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:13:27 INFO - (blobuploader) - INFO - Open directory for files ... 02:13:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:13:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:13:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:13:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:13:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:13:28 INFO - (blobuploader) - INFO - Done attempting. 02:13:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:13:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:13:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:13:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:13:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:13:29 INFO - (blobuploader) - INFO - Done attempting. 02:13:29 INFO - (blobuploader) - INFO - Iteration through files over. 02:13:29 INFO - Return code: 0 02:13:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:13:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:13:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92123406ffdac890d62bb9805c6516e6be9dbdd7292c982ca3bdcac668ec31d675d2be4c744e5bc470995ca72222df98dcefc6216e368f9c87da1da00bc4579a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3765f26475bba26c47ba4a15c3b48288ace26affc3978e96d3f94eb534c8e53a98991ae77841ea58dc53dbf1557a5c65af96faf4e778e373457f995a0770d413"} 02:13:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:13:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:13:29 INFO - Contents: 02:13:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92123406ffdac890d62bb9805c6516e6be9dbdd7292c982ca3bdcac668ec31d675d2be4c744e5bc470995ca72222df98dcefc6216e368f9c87da1da00bc4579a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3765f26475bba26c47ba4a15c3b48288ace26affc3978e96d3f94eb534c8e53a98991ae77841ea58dc53dbf1557a5c65af96faf4e778e373457f995a0770d413"} 02:13:29 INFO - Running post-run listener: copy_logs_to_upload_dir 02:13:29 INFO - Copying logs to upload dir... 02:13:29 INFO - mkdir: /builds/slave/test/build/upload/logs 02:13:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1306.749862 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 46 secs) (at 2016-04-30 02:13:29.620907) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-30 02:13:29.625573) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92123406ffdac890d62bb9805c6516e6be9dbdd7292c982ca3bdcac668ec31d675d2be4c744e5bc470995ca72222df98dcefc6216e368f9c87da1da00bc4579a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3765f26475bba26c47ba4a15c3b48288ace26affc3978e96d3f94eb534c8e53a98991ae77841ea58dc53dbf1557a5c65af96faf4e778e373457f995a0770d413"} build_url:https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010405 build_url: 'https://queue.taskcluster.net/v1/task/Te2lzy9dRNWU_DG1iKrfUg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92123406ffdac890d62bb9805c6516e6be9dbdd7292c982ca3bdcac668ec31d675d2be4c744e5bc470995ca72222df98dcefc6216e368f9c87da1da00bc4579a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3765f26475bba26c47ba4a15c3b48288ace26affc3978e96d3f94eb534c8e53a98991ae77841ea58dc53dbf1557a5c65af96faf4e778e373457f995a0770d413"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-30 02:13:29.655893) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 02:13:29.656240) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tyPwHbqZ6h/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.cpCkSnefW2/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005193 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 02:13:29.704899) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-30 02:13:29.708247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-30 02:13:29.708639) ========= ========= Total master_lag: 2.32 =========