builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0021 starttime: 1462557870.77 results: success (0) buildid: 20160506090722 builduid: 4936efb8919448dea1ac9d3f31163d2c revision: 8c4044b1f2c9ece4b8f83e5646fb267b8fc13e77 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:30.798089) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:30.798575) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:30.798884) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:30.858150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:30.858531) ========= 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.rHvEWjKZYa/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.SNpmrWduZW/Listeners TMPDIR=/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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-05-06 11:04:30-- 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% 14.4M=0.001s 2016-05-06 11:04:31 (14.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.477846 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:31.361031) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:31.361715) ========= 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.rHvEWjKZYa/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.SNpmrWduZW/Listeners TMPDIR=/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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.027248 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:31.417572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 11:04:31.418005) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8c4044b1f2c9ece4b8f83e5646fb267b8fc13e77 --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 8c4044b1f2c9ece4b8f83e5646fb267b8fc13e77 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rHvEWjKZYa/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.SNpmrWduZW/Listeners TMPDIR=/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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-05-06 11:04:31,491 truncating revision to first 12 chars 2016-05-06 11:04:31,491 Setting DEBUG logging. 2016-05-06 11:04:31,491 attempt 1/10 2016-05-06 11:04:31,491 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8c4044b1f2c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 11:04:32,479 unpacking tar archive at: fx-team-8c4044b1f2c9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.232337 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 11:04:32.667563) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:32.670320) ========= script_repo_revision: 8c4044b1f2c9ece4b8f83e5646fb267b8fc13e77 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:32.670761) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:32.671042) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 11:04:32.686488) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-06 11:04:32.686911) ========= /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.rHvEWjKZYa/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.SNpmrWduZW/Listeners TMPDIR=/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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 11:04:32 INFO - MultiFileLogger online at 20160506 11:04:32 in /builds/slave/test 11:04:32 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 11:04:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:04:32 INFO - {'append_to_log': False, 11:04:32 INFO - 'base_work_dir': '/builds/slave/test', 11:04:32 INFO - 'blob_upload_branch': 'Fx-Team', 11:04:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:04:32 INFO - 'branch': 'Fx-Team', 11:04:32 INFO - 'buildbot_json_path': 'buildprops.json', 11:04:32 INFO - 'config_files': ('talos/mac_config.py',), 11:04:32 INFO - 'default_actions': ('clobber', 11:04:32 INFO - 'read-buildbot-config', 11:04:32 INFO - 'download-and-extract', 11:04:32 INFO - 'populate-webroot', 11:04:32 INFO - 'create-virtualenv', 11:04:32 INFO - 'install', 11:04:32 INFO - 'run-tests'), 11:04:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:04:32 INFO - 'download_minidump_stackwalk': True, 11:04:32 INFO - 'download_symbols': 'ondemand', 11:04:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:04:32 INFO - 'tooltool.py': '/tools/tooltool.py', 11:04:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:04:32 INFO - '/tools/misc-python/virtualenv.py')}, 11:04:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:04:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:04:32 INFO - 'installer_path': 'installer.exe', 11:04:32 INFO - 'log_level': 'info', 11:04:32 INFO - 'log_name': 'talos', 11:04:32 INFO - 'log_to_console': True, 11:04:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:04:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:04:32 INFO - 'opt_config_files': (), 11:04:32 INFO - 'pip_index': False, 11:04:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:04:32 INFO - 'cmd': ('bash', 11:04:32 INFO - '-c', 11:04:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:04:32 INFO - 'enabled': True, 11:04:32 INFO - 'halt_on_failure': False, 11:04:32 INFO - 'name': 'check_screen_resolution'},), 11:04:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:04:32 INFO - 'cmd': ('bash', 11:04:32 INFO - '-c', 11:04:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:04:32 INFO - 'enabled': True, 11:04:32 INFO - 'halt_on_failure': False, 11:04:32 INFO - 'name': 'check_screen_resolution'},), 11:04:32 INFO - 'run_cmd_checks_enabled': True, 11:04:32 INFO - 'sps_profile': False, 11:04:32 INFO - 'sps_profile_interval': 0, 11:04:32 INFO - 'suite': 'dromaeojs', 11:04:32 INFO - 'system_bits': '32', 11:04:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:04:32 INFO - 'title': 't-yosemite-r7-0021', 11:04:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:04:32 INFO - 'use_talos_json': True, 11:04:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:04:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:04:32 INFO - 'work_dir': 'build'} 11:04:32 INFO - ##### 11:04:32 INFO - ##### Running clobber step. 11:04:32 INFO - ##### 11:04:32 INFO - Running pre-action listener: _resource_record_pre_action 11:04:32 INFO - Running main action method: clobber 11:04:32 INFO - rmtree: /builds/slave/test/build 11:04:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:04:34 INFO - Running post-action listener: _resource_record_post_action 11:04:34 INFO - ##### 11:04:34 INFO - ##### Finished clobber step (success) 11:04:34 INFO - ##### 11:04:34 INFO - ##### 11:04:34 INFO - ##### Running read-buildbot-config step. 11:04:34 INFO - ##### 11:04:34 INFO - Running pre-action listener: _resource_record_pre_action 11:04:34 INFO - Running main action method: read_buildbot_config 11:04:34 INFO - Using buildbot properties: 11:04:34 INFO - { 11:04:34 INFO - "project": "", 11:04:34 INFO - "product": "firefox", 11:04:34 INFO - "script_repo_revision": "production", 11:04:34 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:04:34 INFO - "repository": "", 11:04:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:04:34 INFO - "buildid": "20160506090722", 11:04:34 INFO - "pgo_build": "False", 11:04:34 INFO - "basedir": "/builds/slave/test", 11:04:34 INFO - "buildnumber": 698, 11:04:34 INFO - "slavename": "t-yosemite-r7-0021", 11:04:34 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:04:34 INFO - "platform": "yosemite_r7", 11:04:34 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:04:34 INFO - "branch": "fx-team", 11:04:34 INFO - "repo_path": "integration/fx-team", 11:04:34 INFO - "revision": "8c4044b1f2c9ece4b8f83e5646fb267b8fc13e77", 11:04:34 INFO - "stage_platform": "macosx64", 11:04:34 INFO - "builduid": "4936efb8919448dea1ac9d3f31163d2c", 11:04:34 INFO - "slavebuilddir": "test" 11:04:34 INFO - } 11:04:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:04:34 INFO - Running post-action listener: _resource_record_post_action 11:04:34 INFO - ##### 11:04:34 INFO - ##### Finished read-buildbot-config step (success) 11:04:34 INFO - ##### 11:04:34 INFO - ##### 11:04:34 INFO - ##### Running download-and-extract step. 11:04:34 INFO - ##### 11:04:34 INFO - Running pre-action listener: _resource_record_pre_action 11:04:34 INFO - Running main action method: download_and_extract 11:04:34 INFO - mkdir: /builds/slave/test/build/tests 11:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:34 INFO - https://queue.taskcluster.net/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:04:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:04:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:04:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/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 11:04:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/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 11:04:37 INFO - Downloaded 1199 bytes. 11:04:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:04:37 INFO - Using the following test package requirements: 11:04:37 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:04:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:37 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 11:04:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:37 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 11:04:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:37 INFO - u'jsshell-mac.zip'], 11:04:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:37 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 11:04:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:04:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:37 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 11:04:37 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:37 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 11:04:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:37 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 11:04:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:37 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 11:04:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:04:37 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')]} 11:04:37 INFO - https://queue.taskcluster.net/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:04:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:04:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:04:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/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 11:04:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/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 11:04:39 INFO - Downloaded 18145140 bytes. 11:04:39 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'] 11:04:39 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 11:04:42 INFO - Return code: 0 11:04:42 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 11:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:42 INFO - https://queue.taskcluster.net/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:04:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:04:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:04:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/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 11:04:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/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 11:04:43 INFO - Downloaded 18145140 bytes. 11:04:43 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'] 11:04:43 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 11:04:46 INFO - Return code: 0 11:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:46 INFO - https://queue.taskcluster.net/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:04:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:04:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:04:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/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 11:04:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/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 11:04:48 INFO - Downloaded 10840152 bytes. 11:04:48 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'] 11:04:48 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 11:04:48 INFO - Return code: 0 11:04: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')]} 11:04:48 INFO - https://queue.taskcluster.net/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:04:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:04:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:04:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 11:04:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/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 11:04:52 INFO - Downloaded 110451339 bytes. 11:04:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:04:52 INFO - mkdir: /builds/slave/test/properties 11:04:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:04:52 INFO - Writing to file /builds/slave/test/properties/build_url 11:04:52 INFO - Contents: 11:04:52 INFO - build_url:https://queue.taskcluster.net/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:04:53 INFO - Running post-action listener: _resource_record_post_action 11:04:53 INFO - Running post-action listener: set_extra_try_arguments 11:04:53 INFO - ##### 11:04:53 INFO - ##### Finished download-and-extract step (success) 11:04:53 INFO - ##### 11:04:53 INFO - ##### 11:04:53 INFO - ##### Running populate-webroot step. 11:04:53 INFO - ##### 11:04:53 INFO - Running pre-action listener: _resource_record_pre_action 11:04:53 INFO - Running main action method: populate_webroot 11:04:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:04:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:04:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:04:53 INFO - '--tppagecycles', 11:04:53 INFO - '7'], 11:04:53 INFO - 'tests': ['tsvgm']}}, 11:04:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:04:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:04:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:04:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:04:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:04:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:04:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:53 INFO - 'talos_options': ['--disable-e10s'], 11:04:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:04:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:04:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:53 INFO - 'talos_options': ['--disable-e10s'], 11:04:53 INFO - 'tests': ['damp', 'tps']}, 11:04:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:53 INFO - 'tests': ['damp', 'tps']}, 11:04:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:04:53 INFO - 'tests': ['dromaeo_dom']}, 11:04:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:04:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:04:53 INFO - 'tests': ['a11yr', 11:04:53 INFO - 'ts_paint', 11:04:53 INFO - 'tpaint', 11:04:53 INFO - 'sessionrestore', 11:04:53 INFO - 'sessionrestore_no_auto_restore', 11:04:53 INFO - 'tabpaint']}, 11:04:53 INFO - 'other-e10s': {'tests': ['a11yr', 11:04:53 INFO - 'ts_paint', 11:04:53 INFO - 'tpaint', 11:04:53 INFO - 'sessionrestore', 11:04:53 INFO - 'sessionrestore_no_auto_restore', 11:04:53 INFO - 'tabpaint']}, 11:04:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:04:53 INFO - 'ts_paint', 11:04:53 INFO - 'tpaint', 11:04:53 INFO - 'sessionrestore', 11:04:53 INFO - 'sessionrestore_no_auto_restore', 11:04:53 INFO - 'tabpaint']}, 11:04:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:04:53 INFO - 'ts_paint', 11:04:53 INFO - 'tpaint', 11:04:53 INFO - 'sessionrestore', 11:04:53 INFO - 'sessionrestore_no_auto_restore', 11:04:53 INFO - 'tabpaint']}, 11:04:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:04:53 INFO - 'tests': ['a11yr', 11:04:53 INFO - 'ts_paint', 11:04:53 INFO - 'tpaint', 11:04:53 INFO - 'sessionrestore', 11:04:53 INFO - 'sessionrestore_no_auto_restore', 11:04:53 INFO - 'tabpaint']}, 11:04:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:04:53 INFO - 'tests': ['a11yr', 11:04:53 INFO - 'ts_paint', 11:04:53 INFO - 'tpaint', 11:04:53 INFO - 'sessionrestore', 11:04:53 INFO - 'sessionrestore_no_auto_restore', 11:04:53 INFO - 'tabpaint']}, 11:04:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:04:53 INFO - 'tests': ['tsvgx', 11:04:53 INFO - 'tsvgr_opacity', 11:04:53 INFO - 'tart', 11:04:53 INFO - 'tscrollx', 11:04:53 INFO - 'cart']}, 11:04:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:04:53 INFO - 'tsvgr_opacity', 11:04:53 INFO - 'tart', 11:04:53 INFO - 'tscrollx', 11:04:53 INFO - 'cart']}, 11:04:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:53 INFO - 'talos_options': ['--disable-e10s'], 11:04:53 INFO - 'tests': ['tp5o']}, 11:04:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:53 INFO - 'tests': ['tp5o']}, 11:04:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:53 INFO - 'talos_options': ['--disable-e10s', 11:04:53 INFO - '--xperf_path', 11:04:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:04:53 INFO - 'tests': ['tp5n']}, 11:04:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:53 INFO - 'talos_options': ['--xperf_path', 11:04:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:04:53 INFO - 'tests': ['tp5n']}}, 11:04:53 INFO - 'talos.zip': {'path': '', 11:04:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:04:53 INFO - Running post-action listener: _resource_record_post_action 11:04:53 INFO - ##### 11:04:53 INFO - ##### Finished populate-webroot step (success) 11:04:53 INFO - ##### 11:04:53 INFO - ##### 11:04:53 INFO - ##### Running create-virtualenv step. 11:04:53 INFO - ##### 11:04:53 INFO - Running pre-action listener: _resource_record_pre_action 11:04:53 INFO - Running main action method: create_virtualenv 11:04:53 INFO - Creating virtualenv /builds/slave/test/build/venv 11:04:53 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 11:04:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:04:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:04:53 INFO - Using real prefix '/tools/python27' 11:04:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:04:54 INFO - Installing distribute.............................................................................................................................................................................................done. 11:04:56 INFO - Installing pip.................done. 11:04:57 INFO - Return code: 0 11:04:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:57 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 0x10b334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81605aab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1d3e00>, '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 0x10affcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ceb20>, '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.rHvEWjKZYa/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.SNpmrWduZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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 11:04:57 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 11:04:57 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 11:04:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rHvEWjKZYa/Render', 11:04:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:04:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:04:57 INFO - 'HOME': '/Users/cltbld', 11:04:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:04:57 INFO - 'LOGNAME': 'cltbld', 11:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:57 INFO - 'MOZ_NO_REMOTE': '1', 11:04:57 INFO - 'NO_EM_RESTART': '1', 11:04:57 INFO - 'PAGER': '/bin/cat', 11:04:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:57 INFO - 'PWD': '/builds/slave/test', 11:04:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:04:57 INFO - 'SHELL': '/bin/bash', 11:04:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SNpmrWduZW/Listeners', 11:04:57 INFO - 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/', 11:04:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:04:57 INFO - 'USER': 'cltbld', 11:04:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:04:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:57 INFO - 'XPC_FLAGS': '0x0', 11:04:57 INFO - 'XPC_SERVICE_NAME': '0', 11:04:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:57 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 11:04:57 INFO - Cleaning up... 11:04:57 INFO - Return code: 0 11:04:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:04:57 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 0x10b334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81605aab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1d3e00>, '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 0x10affcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ceb20>, '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.rHvEWjKZYa/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.SNpmrWduZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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 11:04:57 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 11:04:57 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 11:04:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rHvEWjKZYa/Render', 11:04:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:04:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:04:57 INFO - 'HOME': '/Users/cltbld', 11:04:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:04:57 INFO - 'LOGNAME': 'cltbld', 11:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:04:57 INFO - 'MOZ_NO_REMOTE': '1', 11:04:57 INFO - 'NO_EM_RESTART': '1', 11:04:57 INFO - 'PAGER': '/bin/cat', 11:04:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:04:57 INFO - 'PWD': '/builds/slave/test', 11:04:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:04:57 INFO - 'SHELL': '/bin/bash', 11:04:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SNpmrWduZW/Listeners', 11:04:57 INFO - 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/', 11:04:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:04:57 INFO - 'USER': 'cltbld', 11:04:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:04:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:04:57 INFO - 'XPC_FLAGS': '0x0', 11:04:57 INFO - 'XPC_SERVICE_NAME': '0', 11:04:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:04:57 INFO - Downloading/unpacking psutil>=0.7.1 11:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:04:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:04:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:04:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:05:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:05:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:05:00 INFO - Installing collected packages: psutil 11:05:00 INFO - Running setup.py install for psutil 11:05:00 INFO - building 'psutil._psutil_osx' extension 11:05:00 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 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 INFO - building 'psutil._psutil_posix' extension 11:05:01 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 11:05:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:05:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:05:01 INFO - ^ 11:05:01 INFO - 1 warning generated. 11:05:01 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 11:05:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:05:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:05:01 INFO - Successfully installed psutil 11:05:01 INFO - Cleaning up... 11:05:01 INFO - Return code: 0 11:05:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:05: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')]} 11:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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')]} 11:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:01 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 0x10b334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81605aab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1d3e00>, '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 0x10affcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ceb20>, '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.rHvEWjKZYa/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.SNpmrWduZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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 11:05:01 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 11:05:01 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 11:05:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rHvEWjKZYa/Render', 11:05:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:01 INFO - 'HOME': '/Users/cltbld', 11:05:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:01 INFO - 'LOGNAME': 'cltbld', 11:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:01 INFO - 'MOZ_NO_REMOTE': '1', 11:05:01 INFO - 'NO_EM_RESTART': '1', 11:05:01 INFO - 'PAGER': '/bin/cat', 11:05:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:01 INFO - 'PWD': '/builds/slave/test', 11:05:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:01 INFO - 'SHELL': '/bin/bash', 11:05:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SNpmrWduZW/Listeners', 11:05:01 INFO - 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/', 11:05:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:01 INFO - 'USER': 'cltbld', 11:05:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:01 INFO - 'XPC_FLAGS': '0x0', 11:05:01 INFO - 'XPC_SERVICE_NAME': '0', 11:05:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 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 11:05:01 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 11:05:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:05:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:05:04 INFO - Installing collected packages: mozsystemmonitor 11:05:04 INFO - Running setup.py install for mozsystemmonitor 11:05:04 INFO - Successfully installed mozsystemmonitor 11:05:04 INFO - Cleaning up... 11:05:04 INFO - Return code: 0 11:05:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:04 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 0x10b334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81605aab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1d3e00>, '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 0x10affcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ceb20>, '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.rHvEWjKZYa/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.SNpmrWduZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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 11:05:04 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 11:05:04 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 11:05:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rHvEWjKZYa/Render', 11:05:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:04 INFO - 'HOME': '/Users/cltbld', 11:05:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:04 INFO - 'LOGNAME': 'cltbld', 11:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:04 INFO - 'MOZ_NO_REMOTE': '1', 11:05:04 INFO - 'NO_EM_RESTART': '1', 11:05:04 INFO - 'PAGER': '/bin/cat', 11:05:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:04 INFO - 'PWD': '/builds/slave/test', 11:05:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:04 INFO - 'SHELL': '/bin/bash', 11:05:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SNpmrWduZW/Listeners', 11:05:04 INFO - 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/', 11:05:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:04 INFO - 'USER': 'cltbld', 11:05:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:04 INFO - 'XPC_FLAGS': '0x0', 11:05:04 INFO - 'XPC_SERVICE_NAME': '0', 11:05:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:04 INFO - Downloading/unpacking blobuploader==1.2.4 11:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:07 INFO - Downloading blobuploader-1.2.4.tar.gz 11:05:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:05:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:05:07 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 11:05:07 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 11:05:07 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 11:05:07 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 11:05:07 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 11:05:07 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 11:05:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:05:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:05:08 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 11:05:08 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 11:05:08 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 11:05:08 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 11:05:08 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 11:05:08 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 11:05:08 INFO - Downloading docopt-0.6.1.tar.gz 11:05:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:05:08 INFO - Installing collected packages: blobuploader, requests, docopt 11:05:08 INFO - Running setup.py install for blobuploader 11:05:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:05:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:05:08 INFO - Running setup.py install for requests 11:05:09 INFO - Running setup.py install for docopt 11:05:09 INFO - Successfully installed blobuploader requests docopt 11:05:09 INFO - Cleaning up... 11:05:09 INFO - Return code: 0 11:05:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:09 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')]} 11:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:09 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')]} 11:05:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:09 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 0x10b334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81605aab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1d3e00>, '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 0x10affcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ceb20>, '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.rHvEWjKZYa/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.SNpmrWduZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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 11:05:09 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 11:05:09 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 11:05:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rHvEWjKZYa/Render', 11:05:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:09 INFO - 'HOME': '/Users/cltbld', 11:05:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:09 INFO - 'LOGNAME': 'cltbld', 11:05:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:09 INFO - 'MOZ_NO_REMOTE': '1', 11:05:09 INFO - 'NO_EM_RESTART': '1', 11:05:09 INFO - 'PAGER': '/bin/cat', 11:05:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:09 INFO - 'PWD': '/builds/slave/test', 11:05:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:09 INFO - 'SHELL': '/bin/bash', 11:05:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SNpmrWduZW/Listeners', 11:05:09 INFO - 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/', 11:05:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:09 INFO - 'USER': 'cltbld', 11:05:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:09 INFO - 'XPC_FLAGS': '0x0', 11:05:09 INFO - 'XPC_SERVICE_NAME': '0', 11:05:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:09 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-WWlY_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:09 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-XP8bI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:09 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-GmMrpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:10 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-EWCEG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:10 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-NbIAMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:10 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-fzNv8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:10 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-EOg7EO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:10 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-GXDRsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:10 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-X5zS1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:10 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-et_11z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:10 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-a7isg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:10 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-HwFJt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:10 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-Cz09Xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:11 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-uSnd6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:11 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-WwoBgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:11 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-K7AiMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:11 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-SUrAMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:05:11 INFO - Running setup.py install for manifestparser 11:05:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:05:11 INFO - Running setup.py install for mozcrash 11:05:11 INFO - Running setup.py install for mozdebug 11:05:11 INFO - Running setup.py install for mozdevice 11:05:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:05:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:05:12 INFO - Running setup.py install for mozfile 11:05:12 INFO - Running setup.py install for mozhttpd 11:05:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:05:12 INFO - Running setup.py install for mozinfo 11:05:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:05:12 INFO - Running setup.py install for mozInstall 11:05:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:05:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:05:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:05:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:05:12 INFO - Running setup.py install for mozleak 11:05:12 INFO - Running setup.py install for mozlog 11:05:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:05:12 INFO - Running setup.py install for moznetwork 11:05:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:05:13 INFO - Running setup.py install for mozprocess 11:05:13 INFO - Running setup.py install for mozprofile 11:05:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:05:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:05:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:05:13 INFO - Running setup.py install for mozrunner 11:05:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:05:13 INFO - Running setup.py install for mozscreenshot 11:05:13 INFO - Running setup.py install for moztest 11:05:13 INFO - Running setup.py install for mozversion 11:05:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:05:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:05:14 INFO - Cleaning up... 11:05:14 INFO - Return code: 0 11:05:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:14 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 0x10b334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81605aab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1d3e00>, '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 0x10affcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ceb20>, '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.rHvEWjKZYa/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.SNpmrWduZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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 11:05:14 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 11:05:14 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 11:05:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rHvEWjKZYa/Render', 11:05:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:14 INFO - 'HOME': '/Users/cltbld', 11:05:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:14 INFO - 'LOGNAME': 'cltbld', 11:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:14 INFO - 'MOZ_NO_REMOTE': '1', 11:05:14 INFO - 'NO_EM_RESTART': '1', 11:05:14 INFO - 'PAGER': '/bin/cat', 11:05:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:14 INFO - 'PWD': '/builds/slave/test', 11:05:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:14 INFO - 'SHELL': '/bin/bash', 11:05:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SNpmrWduZW/Listeners', 11:05:14 INFO - 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/', 11:05:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:14 INFO - 'USER': 'cltbld', 11:05:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:14 INFO - 'XPC_FLAGS': '0x0', 11:05:14 INFO - 'XPC_SERVICE_NAME': '0', 11:05:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:14 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-TT6agS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:14 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)) 11:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:14 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-2hkwwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:14 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)) 11:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:14 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-4x_IAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:14 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)) 11:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:14 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-gn64BT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:14 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)) 11:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:14 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-YLRUnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:14 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)) 11:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:14 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-krjKcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:14 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)) 11:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:14 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-110yPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:15 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-hdBsU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:15 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-ASBKSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:15 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-WITqOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:15 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-pr9XIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:15 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-1aTlTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:15 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-tkKpu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:15 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-282Tw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:15 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-K5eV2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:15 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-OVDpEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:15 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)) 11:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:15 INFO - Running setup.py (path:/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/pip-CdHzhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:16 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)) 11:05:16 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)) 11:05:16 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)) 11:05:16 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)) 11:05:16 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)) 11:05:16 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)) 11:05:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:18 INFO - Downloading blessings-1.6.tar.gz 11:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:05:18 INFO - Installing collected packages: blessings 11:05:18 INFO - Running setup.py install for blessings 11:05:18 INFO - Successfully installed blessings 11:05:18 INFO - Cleaning up... 11:05:18 INFO - Return code: 0 11:05:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:05:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:19 INFO - Reading from file tmpfile_stdout 11:05:19 INFO - Current package versions: 11:05:19 INFO - blessings == 1.6 11:05:19 INFO - blobuploader == 1.2.4 11:05:19 INFO - docopt == 0.6.1 11:05:19 INFO - manifestparser == 1.1 11:05:19 INFO - mozInstall == 1.12 11:05:19 INFO - mozcrash == 0.17 11:05:19 INFO - mozdebug == 0.1 11:05:19 INFO - mozdevice == 0.48 11:05:19 INFO - mozfile == 1.2 11:05:19 INFO - mozhttpd == 0.7 11:05:19 INFO - mozinfo == 0.9 11:05:19 INFO - mozleak == 0.1 11:05:19 INFO - mozlog == 3.1 11:05:19 INFO - moznetwork == 0.27 11:05:19 INFO - mozprocess == 0.22 11:05:19 INFO - mozprofile == 0.28 11:05:19 INFO - mozrunner == 6.11 11:05:19 INFO - mozscreenshot == 0.1 11:05:19 INFO - mozsystemmonitor == 0.0 11:05:19 INFO - moztest == 0.7 11:05:19 INFO - mozversion == 1.4 11:05:19 INFO - psutil == 3.1.1 11:05:19 INFO - requests == 1.2.3 11:05:19 INFO - wsgiref == 0.1.2 11:05:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:19 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 0x10b334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81605aab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1d3e00>, '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 0x10affcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ceb20>, '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.rHvEWjKZYa/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.SNpmrWduZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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 11:05:19 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 11:05:19 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 11:05:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rHvEWjKZYa/Render', 11:05:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:19 INFO - 'HOME': '/Users/cltbld', 11:05:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:19 INFO - 'LOGNAME': 'cltbld', 11:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:19 INFO - 'MOZ_NO_REMOTE': '1', 11:05:19 INFO - 'NO_EM_RESTART': '1', 11:05:19 INFO - 'PAGER': '/bin/cat', 11:05:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:19 INFO - 'PWD': '/builds/slave/test', 11:05:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:19 INFO - 'SHELL': '/bin/bash', 11:05:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SNpmrWduZW/Listeners', 11:05:19 INFO - 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/', 11:05:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:19 INFO - 'USER': 'cltbld', 11:05:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:19 INFO - 'XPC_FLAGS': '0x0', 11:05:19 INFO - 'XPC_SERVICE_NAME': '0', 11:05:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:19 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)) 11:05:19 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)) 11:05:19 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)) 11:05:19 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)) 11:05:19 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)) 11:05:19 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)) 11:05:19 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)) 11:05:19 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)) 11:05:19 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)) 11:05:19 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)) 11:05:19 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)) 11:05:19 INFO - Cleaning up... 11:05:19 INFO - Return code: 0 11:05:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81605aab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1d3e00>, '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 0x10affcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ceb20>, '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.rHvEWjKZYa/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.SNpmrWduZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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 11:05:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:05:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rHvEWjKZYa/Render', 11:05:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:19 INFO - 'HOME': '/Users/cltbld', 11:05:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:19 INFO - 'LOGNAME': 'cltbld', 11:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:19 INFO - 'MOZ_NO_REMOTE': '1', 11:05:19 INFO - 'NO_EM_RESTART': '1', 11:05:19 INFO - 'PAGER': '/bin/cat', 11:05:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:19 INFO - 'PWD': '/builds/slave/test', 11:05:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:19 INFO - 'SHELL': '/bin/bash', 11:05:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SNpmrWduZW/Listeners', 11:05:19 INFO - 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/', 11:05:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:19 INFO - 'USER': 'cltbld', 11:05:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:19 INFO - 'XPC_FLAGS': '0x0', 11:05:19 INFO - 'XPC_SERVICE_NAME': '0', 11:05:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:19 INFO - Downloading/unpacking jsonschema 11:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:05:22 INFO - Downloading/unpacking functools32 (from jsonschema) 11:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:22 INFO - Downloading functools32-3.2.3-2.tar.gz 11:05:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:05:22 INFO - warning: no files found matching '*.txt' 11:05:22 INFO - no previously-included directories found matching 'build' 11:05:22 INFO - no previously-included directories found matching 'dist' 11:05:22 INFO - no previously-included directories found matching '.git*' 11:05:22 INFO - Installing collected packages: jsonschema, functools32 11:05:22 INFO - Running setup.py install for functools32 11:05:22 INFO - warning: no files found matching '*.txt' 11:05:22 INFO - no previously-included directories found matching 'build' 11:05:22 INFO - no previously-included directories found matching 'dist' 11:05:22 INFO - no previously-included directories found matching '.git*' 11:05:22 INFO - Successfully installed jsonschema functools32 11:05:22 INFO - Cleaning up... 11:05:22 INFO - Return code: 0 11:05:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:05: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')]} 11:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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')]} 11:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b334a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b344420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81605aab10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1d3e00>, '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 0x10affcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ceb20>, '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.rHvEWjKZYa/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.SNpmrWduZW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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 11:05:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 11:05:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 11:05:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rHvEWjKZYa/Render', 11:05:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:22 INFO - 'HOME': '/Users/cltbld', 11:05:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:22 INFO - 'LOGNAME': 'cltbld', 11:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:22 INFO - 'MOZ_NO_REMOTE': '1', 11:05:22 INFO - 'NO_EM_RESTART': '1', 11:05:22 INFO - 'PAGER': '/bin/cat', 11:05:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:22 INFO - 'PWD': '/builds/slave/test', 11:05:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:22 INFO - 'SHELL': '/bin/bash', 11:05:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SNpmrWduZW/Listeners', 11:05:22 INFO - 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/', 11:05:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:22 INFO - 'USER': 'cltbld', 11:05:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:22 INFO - 'XPC_FLAGS': '0x0', 11:05:22 INFO - 'XPC_SERVICE_NAME': '0', 11:05:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:23 INFO - Downloading/unpacking flake8 11:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:05:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:05:25 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 11:05:25 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 11:05:25 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 11:05:25 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 11:05:25 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 11:05:25 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 11:05:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:05:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:05:25 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 11:05:25 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 11:05:25 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 11:05:25 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 11:05:25 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 11:05:25 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 11:05:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:05:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:05:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:05:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:05:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:05:26 INFO - no previously-included directories found matching 'docs/_build' 11:05:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:05:26 INFO - Running setup.py install for pep8 11:05:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:05:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:05:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:05:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:05:27 INFO - no previously-included directories found matching 'docs/_build' 11:05:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:05:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:05:27 INFO - Cleaning up... 11:05:27 INFO - Return code: 0 11:05:27 INFO - Running post-action listener: _resource_record_post_action 11:05:27 INFO - Running post-action listener: _start_resource_monitoring 11:05:27 INFO - Starting resource monitoring. 11:05:27 INFO - ##### 11:05:27 INFO - ##### Finished create-virtualenv step (success) 11:05:27 INFO - ##### 11:05:27 INFO - ##### 11:05:27 INFO - ##### Running install step. 11:05:27 INFO - ##### 11:05:27 INFO - Running pre-action listener: _resource_record_pre_action 11:05:27 INFO - Running main action method: install 11:05:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:27 INFO - Reading from file tmpfile_stdout 11:05:27 INFO - Detecting whether we're running mozinstall >=1.0... 11:05:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:05:27 INFO - Reading from file tmpfile_stdout 11:05:27 INFO - Output received: 11:05:27 INFO - Usage: mozinstall [options] installer 11:05:27 INFO - Options: 11:05:27 INFO - -h, --help show this help message and exit 11:05:27 INFO - -d DEST, --destination=DEST 11:05:27 INFO - Directory to install application into. [default: 11:05:27 INFO - "/builds/slave/test"] 11:05:27 INFO - --app=APP Application being installed. [default: firefox] 11:05:27 INFO - mkdir: /builds/slave/test/build/application 11:05:27 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'] 11:05:27 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 11:06:01 INFO - Reading from file tmpfile_stdout 11:06:01 INFO - Output received: 11:06:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:06:01 INFO - Running post-action listener: _resource_record_post_action 11:06:01 INFO - ##### 11:06:01 INFO - ##### Finished install step (success) 11:06:01 INFO - ##### 11:06:01 INFO - ##### 11:06:01 INFO - ##### Running run-tests step. 11:06:01 INFO - ##### 11:06:01 INFO - Running pre-action listener: _resource_record_pre_action 11:06:01 INFO - Running main action method: run_tests 11:06:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:06:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:06:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:06:01 INFO - 2016-05-06 11:06:01.236 screenresolution[1669:7700] starting screenresolution argv=screenresolution get 11:06:01 INFO - 2016-05-06 11:06:01.239 screenresolution[1669:7700] Display 0: 1600x1200x32@60 11:06:01 INFO - 2016-05-06 11:06:01.245 screenresolution[1670:7702] starting screenresolution argv=screenresolution list 11:06:01 INFO - Available Modes on Display 0 11:06:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:06:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:06:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:06:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:06:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:06:01 INFO - Intel Iris: 11:06:01 INFO - Chipset Model: Intel Iris 11:06:01 INFO - Type: GPU 11:06:01 INFO - Bus: Built-In 11:06:01 INFO - VRAM (Dynamic, Max): 1536 MB 11:06:01 INFO - Vendor: Intel (0x8086) 11:06:01 INFO - Device ID: 0x0a2e 11:06:01 INFO - Revision ID: 0x0009 11:06:01 INFO - Displays: 11:06:01 INFO - Display: 11:06:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:06:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:06:01 INFO - Display Serial Number: 1600x1200 60 11:06:01 INFO - Main Display: Yes 11:06:01 INFO - Mirror: Off 11:06:01 INFO - Online: Yes 11:06:01 INFO - Rotation: Supported 11:06:01 INFO - Return code: 0 11:06:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:06:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:06:01 INFO - Python 2.7.3 11:06:01 INFO - Return code: 0 11:06:01 INFO - grabbing minidump binary from tooltool 11:06: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')]} 11:06:01 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 0x10b1d3e00>, '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 0x10affcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ceb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:06:01 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 11:06:01 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 11:06:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:06:01 INFO - Return code: 0 11:06:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:06:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:06:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:06:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:06:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:06:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:06:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:06:02 INFO - Return code: 0 11:06:02 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/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0021', '--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 11:06:02 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/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0021 --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 11:06:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rHvEWjKZYa/Render', 11:06:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:06:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:06:02 INFO - 'HOME': '/Users/cltbld', 11:06:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:06:02 INFO - 'LOGNAME': 'cltbld', 11:06:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:06:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:02 INFO - 'MOZ_NO_REMOTE': '1', 11:06:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:06:02 INFO - 'NO_EM_RESTART': '1', 11:06:02 INFO - 'PAGER': '/bin/cat', 11:06:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:02 INFO - 'PWD': '/builds/slave/test', 11:06:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:06:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:06:02 INFO - 'SHELL': '/bin/bash', 11:06:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SNpmrWduZW/Listeners', 11:06:02 INFO - 'TMPDIR': '/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/', 11:06:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:06:02 INFO - 'USER': 'cltbld', 11:06:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:06:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:02 INFO - 'XPC_FLAGS': '0x0', 11:06:02 INFO - 'XPC_SERVICE_NAME': '0', 11:06:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:06:02 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/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0021', '--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 11:06:02 INFO - mozversion application_buildid: 20160506090722 11:06:02 INFO - mozversion application_changeset: 8c4044b1f2c9ece4b8f83e5646fb267b8fc13e77 11:06:02 INFO - mozversion application_display_name: Nightly 11:06:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:06:02 INFO - mozversion application_name: Firefox 11:06:02 INFO - mozversion application_remotingname: firefox 11:06:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:06:02 INFO - mozversion application_vendor: Mozilla 11:06:02 INFO - mozversion application_version: 49.0a1 11:06:02 INFO - mozversion platform_buildid: 20160506090722 11:06:02 INFO - mozversion platform_changeset: 8c4044b1f2c9ece4b8f83e5646fb267b8fc13e77 11:06:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:06:02 INFO - mozversion platform_version: 49.0a1 11:06:02 INFO - using testdate: 1462557962 11:06:02 INFO - actual date: 1462557962 11:06:02 INFO - starting webserver on 'localhost:49273' 11:06:02 INFO - SUITE-START | Running 2 tests 11:06:02 INFO - TEST-START | dromaeo_css 11:06:02 INFO - operating with platform_type : mac_ 11:06:02 INFO - Initialising browser for dromaeo_css test... 11:06:02 INFO - TEST-INFO | started process 1688 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/tmpvgoMai/profile http://localhost:49273/getInfo.html) 11:06:05 INFO - PROCESS | 1688 | __metrics Screen width/height:1600/1200 11:06:05 INFO - PROCESS | 1688 | colorDepth:24 11:06:05 INFO - PROCESS | 1688 | Browser inner width/height: 1024/689 11:06:05 INFO - PROCESS | 1688 | __metrics 11:06:05 INFO - PROCESS | 1688 | 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" 11:06:05 ERROR - PROCESS | 1688 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:06:06 INFO - TEST-INFO | 1688: exit 0 11:06:06 INFO - Browser initialized. 11:06:06 INFO - Running cycle 1/1 for dromaeo_css test... 11:06:06 INFO - TEST-INFO | started process 1691 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/tmpvgoMai/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:06:17 INFO - PROCESS | 1691 | RSS: Main: 239464448 11:06:17 INFO - PROCESS | 1691 | 11:07:09 INFO - PROCESS | 1691 | 1462558029585 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] 11:09:20 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 11:09:21 INFO - PROCESS | 1691 | RSS: Main: 343732224 11:09:21 INFO - PROCESS | 1691 | 11:12:24 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 11:12:25 INFO - PROCESS | 1691 | RSS: Main: 374083584 11:12:25 INFO - PROCESS | 1691 | 11:15:27 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 11:15:28 INFO - PROCESS | 1691 | RSS: Main: 368754688 11:15:28 INFO - PROCESS | 1691 | 11:18:30 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 11:18:31 INFO - PROCESS | 1691 | RSS: Main: 373276672 11:18:31 INFO - PROCESS | 1691 | 11:21:34 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 11:21:35 INFO - PROCESS | 1691 | RSS: Main: 337936384 11:21:35 INFO - PROCESS | 1691 | 11:24:38 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 11:24:38 INFO - PROCESS | 1691 | RSS: Main: 389402624 11:24:38 INFO - PROCESS | 1691 | 11:24:38 INFO - PROCESS | 1691 | __start_tp_report 11:24:38 INFO - PROCESS | 1691 | _x_x_mozilla_page_load 11:24:38 INFO - PROCESS | 1691 | _x_x_mozilla_page_load_details 11:24:38 INFO - PROCESS | 1691 | |i|pagename|runs| 11:24:38 INFO - PROCESS | 1691 | |0;dojo.html;3271.8691252349904;3455.844486998085;3488.8953331400066;3506.386382383083;3534.982325088374;5601.411851755568;5628.690422026787;5631.8028868989595;5717.799877004306;5725.2843394575675;5577.107662773957;5729;5729.054705973509;5737;5747;5092.999999999991;5170.508801663836;5176.456472070435;5206.921896171561;5235.921461178085;4372.73763574185;5222.503862133091;5238.60710446718;5239.161734122541;5242.659227150242;5164.974175129119;5168.379794424674;5170.482951704818;5186.377634683843;5227;5243.370795504526;5260.894782104375;5266.289050978107;5275.393329767049;5320.228566857822;5088.211327244283;5096.159133742928;5101.362329708786;5189.636725429222;5204.817831375922;4677.666864943475;5616.494515493625;5622.437756224385;5652.491275785158;5669.121286200645;6244.718987645521;6272.717727702265;6273;6299.496040316763;6313.40022697843;5130.743462826844;6232.719527621268;6316.242050953869;6317.1156038154695;6322.178116844781;5870.677112758796;5871.383504732027;5876.294844618587;5882.705864706748;5892.587518873681;6284.497240220771;6289.339619339995;6290.842728931813;6314.62112273265;6314.71583778731;5980.162777211194;6076.939230607726;6079.908801367983;6082.270127584629;6189.81430557075;5639.182318563785;6067.7876274331065;6187.474064704551;6193.194884664965;6200.3799620037635;6129.511613759063;6163.599366041193;6191.25704915413;6236.158118654014;6240.219972503437;6213.65824879627;6262.3111457739615;6290.24517057956;6308.71610777507;6357.777477788348;4826.831060912851;4892.804287828447;4911.607071434277;4914.65597408178;4944.431390390151;4391.626711729475;4443.777811109431;4469;4476.082403107355;4487.169873573399;5731.312242530839;5780.595358324961;5818.854528636733;5849.61977471463;5863.618864773776;6229.595076320024;6322.304546499881;6329.303776584572;6356.586821856565;6370.968145159337;5760.107183386582;5763.827085187451;5805.82582522525;5829.569586935034;5851.765929362863;5317.494837990384;5347;5370.9731451342495;5379.488948549882;5382.811601593926;6296.488324326466;6339.778107766206;6339.968300158562;6344.619322840645;6346.555741098079;6207.751689932352;6243.875122497616;6254.499640028877;6284.5600807943;6286.27707813589;6205.379462053939;6264.718087685974;6317.5893566918;6321.462675672619;6389.137466442156;6210.441060304594;6218.657973811484;6219.40915613016;6228.56400051992;6229.937700622936;6117.888303246575;6265.4361107500545;6302.212223472066;6308;6318.842028949313;5964.045752679551;5968.895754285471;6121.387861213843;6122.969385153046;6142.262928448615;6038.486728628116;6093.756249750138;6094.878102438015;6095.542834287535;6107.236595425572;5584.274044374205;5789.478946894799;5849.970750146222;5940.495057920125;5941.554383421347;6002.219711437485;6069.514438844793;6074;6084.817455476343;6094.664793436226;5893.9115913260475;5914.319853216935;5919.112133180058;5923;5926.288845338587 11:24:38 INFO - PROCESS | 1691 | |1;ext.html;512.5822454699418;525.0627629681019;531.1130412211606;558.1989844573038;559.9468050535199;30645.846770766144;32342.353152936957;32431.67568324319;42323.36514952278;43802.78098609502;45012;45218.32172517415;45287.54712452875;45364;45395.546044539544;9574.952125239364;9797.363171393872;9801.264905132108;9809.50952452378;9815.999999999964;8491.490510569385;8680.435771674853;9578.281628877827;11646.825297620542;11843.289402635826;11774.116941229398;11831.940840295787;11836;11854.170208085437;11863.94068029663;8577.828443431097;11762.882371176307;11812.232204906739;11828.349440780754;11845.111616628787;8560.357973151977;8573.271271941892;8576.313894888393;11381.260218085798;11719.648410547697;34349;40925.59074409248;41470.58529414697;41610.79194604038;42027.94930126741;3245.594300712411;3296.5384846121324;3344.3144155448076;3843.865464708722;3929.9978505480917;25046.248612541654;28418.431631367464;28701;28936.27659308513;29504;2720;2733.740294672003;2764.834109953389;2827.943441131166;2871.5261981772983;432292;433624;433828;434249;434921;57750;60975.78048439007;63535.36464635387;64597;64802.35197647963;68958.62082758416;69136.96294555586;69235.61528769498;69277.65361173263;69279;16465.588360291087;17073.829261707473;17090.316387344617;17098.435234714892;17107.401240956508;11116.499757510932;11892.405379730979;12142.514299427923;13140.01449891243;13213.867861321263;15308.846911530742;17871.28514859391;18203.08984550767;18316.17577209029;18497.445076647455;6110.388961103854;6785.457163426914;7002.719891204295;7056.7530136444975;7108.4668649851465;960.2701946520694;1032.8999615409564;1066.3655125200494;1108.362691452402;1163.8545181852269;29594.556081658793;29607.25981850471;29997.700022999492;30320.696793032228;30351.392972140435;1067.3928383307302;1102.3000394749392;1117.2681893359863;1160.802663547199;1222.7798996180595;15178;15282.159481228638;15320.770188447183;15433.5369938902;15730.370785168694;596086;603773;604733;604746;606791;60094.69952650209;61616.38383616106;61708.38291617027;61710;61797;1326.2506683723666;1374.3815283122635;1467.1343907094865;1496.132243298884;1505.894587378873;30215.395692086477;30434.543481847348;30656.693433065382;30967.535486967263;31334.68665313409;52211.73894130505;52613.7369313151;53225.7338713304;53280.73359633177;53502.66243343947;516868;517900;521761;521786;522726;33893.152671183416;35315.646843531234;41677.374839376826;42888.71333859989;44218.683598730866;46623;46734.53265467438;46956.295655565875;48231;48589.51410485991;36435.089122772144;36882.44676329911;38472.230555388174;39385.40921886231;39414.605853941095;36844.81577592095;37223.81388093042;37594.624053759115;37847.43228851625;38091.42862857004;37130.8143459281;37964.67123650659;38346.616533834305;38460.03849903663;41234.9691257721;28899;33172.004839854846;33429.49855752117;33617.495737564444;34601.82699086489;22708.772912270666;23420.946057427114;24716.629250560898;25618.487630247662;26204 11:24:38 INFO - PROCESS | 1691 | |2;jquery.html;393.8227797491129;398.8584052661303;400.40339893558604;401.6104378752609;425.37469919218756;14691.853081469182;19703.802961970396;20387.694184587242;21026.99999999998;21069.367918962416;16491.09298988555;16937.3225070997;17019.659606807858;20167.697484537744;20579.794202057976;5948.464638182562;6162.352952939944;6188.659623720693;6200.78297259596;6210.565260431772;5773.191753154561;6116.388361163892;6123.908141377883;6126.356732543063;6193;6029.668368239745;6058.182145410357;6062.181605483247;6073.210482637273;6079.726412311456;5893.823185304448;5947.6134051286745;6056.242969628796;6062.45407421107;6063.181470501492;4788.353572267734;5074.974625126888;5271.499207575274;5280.128778751501;5293.23248129023;24666.50666986668;25476.36309092269;25852.482950340887;25934.87032564844;26633.73366266332;2163.2536774812665;2241.68616393705;2335.497867958397;2500.6749122614124;2829.349249672566;12719.364031798374;14591.78112328316;14847.183404912725;14876.553703388916;15094.471693490676;1671.2479384277124;1700.1159397113431;1709.5640611644076;1760.4014635024025;1818.1272988965238;119591;124461.37769311186;128476;129840.31437170606;132403;9201.63193472267;11084.501197446134;11439.199256052163;11475.598354057567;11569.19015668912;14221.502247421291;14273;14302.213378264296;14313.928430357782;14324.427022919175;7576.090869095744;7657.592371394676;7804.921950780533;8169.428140030141;8976.506292153994;8847.601857916408;8920.73237802867;8926.68756593516;9078.273738100936;9080.500572468578;5642.6614403135945;6662.333766623299;6781.6609169541325;6798.2182049064;6807.3873351398615;1741.8503787500442;1762.9069976614326;1777.0496542751775;1781.4744650328184;1878.3519685708409;818.8157664525434;947.5262368815593;978.2663002747939;1041.1774697988503;1080.8432777247256;20885.95570221483;21453.141874325163;21705.457363566038;21830.672539911913;21925.08316633766;945.281585994649;946.432140715579;972.1930797438109;1056.841473778924;1124.966251012471;9736.269779766546;11348.375839328752;11523.827142592867;11526.193166478433;11589.710257243636;525522;532322;532802;605717;618799.9060004671;147036.26481867523;149852.2507387456;150592;156892;157291;1037.5694086953981;1049.821530339844;1090.7709381029913;1092.5029111754134;1094.2941802537318;14933.701325973638;17429.38997135094;17653.574422224967;17830.5542361442;18141.637167256846;38895.805520972215;51997.480025199264;53021.73489132529;53453;54192;73613.63193183999;74980.62509687417;78050.6097469509;82460;89648;7241;7482.139553951365;7616.69533218665;7850.175731548334;7924.683012679428;12573.245605263714;12827.166234194747;12961.805572916603;13204.60386188416;13253.734925301633;10952.56189752401;11422.828657569977;11434;11489;11541.134414919083;7602.163761986288;11061.668149955514;11116.333020018825;11338.319700817978;11445.828312575484;6932.341427564374;7014.403775679124;8065.717699880476;8226.588670566567;8529.872051919103;12251.387430628254;12307.446164922421;12439.574283014725;12696.238225706487;12769;10088.54601542947;10703.892961070289;12092.274463532216;12136.453859576517;12251.509939602316 11:24:38 INFO - PROCESS | 1691 | |3;mootools.html;1425.1947649577994;1593.9123348215844;1637.9181040947954;1882.463497903098;2002.539415934336;7799.92200077999;8528.317734581235;8828.646854125827;8853;8909;8566.57167141642;8778.253848422875;8784.692535761249;8786.384953053288;8796.076411976746;2438.817088718344;2466.6176742604925;2469.1604854349516;2471.9258422247362;2474.1216868011866;2420.4190994161363;2469.592517234654;2472.6414669872856;2476.702795664523;2478.033566908907;2412.1316326122583;2446.779789818916;2461.520003599299;2463.4087818923417;2473.530029294437;2373.9406514837096;2397.5804234258935;2411.3730430088126;2442.829001969845;2450.2649205238536;2400.840244542879;2429.1740808125146;2431.234161239204;2436.8537887726616;2446.266120163944;8517.787055323604;8616.396852220347;8710.390272680916;8715.389922705413;8753.693620723308;5320.095583750733;5428.294321738149;5504.669719816824;5508.9724551377385;5509;3423.2639982403653;3458.8789392371145;3515.542979412686;3562.7684200526883;3580.3197392495345;1041.510490069666;1053.1890444357878;1062.2988827373897;1062.8883967183413;1063.6596289187464;11298.378589177591;11384.975352218256;11418.942905285503;11429.714257143554;11454.656360309204;4534.13851368239;4581.427321584802;4652.278896771006;4689.531046895283;4694.108119457293;9007.909920900838;9019.188273055457;9038.909610903938;9039.231665308522;9047.095290470901;5264.894702105937;5272.261883336336;5273.314469119066;5277.208418737144;5278.340207474066;3900.297946369624;4406.867793966187;4729.36153619257;4793.976030119897;4883.438404583448;3638.0541059324764;3793.7534060285993;3826.426036094608;3905;3928.4303775952326;1024.3341827811832;1104.2380757277454;1223.9020878329711;1227.9508819647115;1232.1128787273328;655.2824657000576;789.6249281591313;880.2869675562814;880.335346813152;982.7985263021063;5721.76981948883;5776.249087618583;5926.674032928144;6012.128241405059;6151;980.7008862296925;995.1938929467154;998.8152191844532;1041.2555023158477;1054.9472526373804;3868.941965870514;4018.8392464301687;4025.5169379674626;4064.7967601620394;4064.837406503707;10268.383896966057;10624.734381640521;10694.732631684115;10803.297785643908;10859.619913302997;10613.787724245472;10661.253712240068;10758.94620526908;10767.300125491818;10769.730756730987;7688.231176882379;7907.920920790833;8061.798455038554;8179.427440079137;8244.340452763765;5763.625364351471;5882.823515294548;5918.674472904032;5963.552733545088;6083.087536869328;9703.757406064904;9955;10036.94981525117;10038.498075096362;10059.647912323033;9710.077542633438;9892.752681183028;10032.899671003488;10146.239032072477;10193.541290642088;8117.269445749911;8322.292605128632;8335.166483351617;8337.749867503984;8347.373946953881;8055.959720201361;8147.674093036212;8188.976377952756;8292.212239837207;8308.418410711192;7395.630218489162;7436.665350059152;7447.106347238366;7577.772666819784;7601.961990190013;7254.238304977901;7348.632568371667;7378.372838308804;7454.366378857858;7506.812329691801;6951.478639101986;6966.895496567657;7027.789166325018;7047.859042819217;7056.294370562902;4930.309756634002;4965.45380008206;4989.151844186569;5047.646664733434;5182;4969.701817890938;5015.699058056528;5032.823851165192;5079.6952182869145;5245 11:24:38 INFO - PROCESS | 1691 | |4;prototype.html;312.62485017978423;318.666992992323;325.25516567061425;329.16884865714053;329.58143158189085;5094.235864620309;5329.748853199545;5343.198520221959;5397.4062853086125;5397.487238712326;4640.257558790594;5257;5313.814016509423;5356.071814146904;5407.864803379917;3852.3226357681424;3863.2080423513253;4014.7791871447057;4069.5786007325596;4105.7331273467125;3357.446021406465;3389.8983030509125;3852.5376954765466;4010.95715114071;4029.2747305485;3716.981415092934;3988.6809055275507;4012.6589239914642;4022.3966405039187;4022.919541609166;3062.6171728533823;3804.9619503804884;4013.695548946604;4034.5072323240297;4040.5555388907196;3101.4727496325454;3241.578594782663;3484.3205574912904;3516;3537.2748586539697;4294.592013758688;4511.263163681973;5079.4412614612365;5104.872378190538;5132.101882170605;4291.828326866922;4346.478422589278;4643.210654188762;4647.721136731807;4667.649926255544;4999.450060493343;5115.61632877532;5655.208270842086;5708.942910570882;5711.514521265697;3562.964370356315;3891.6886649068006;3910.65082546521;3919.2945269851416;3960.3663413853646;4937.604991600663;5660;5888.322842873082;5902.321233058167;6046.062860256668;3334.3664703706218;3612.8735494257576;3853.2293541291856;3878.5733569307354;3901.1612503311926;3267.0852161394373;3912.9608703912595;3956.208758248362;3959.6436320731273;3973.5231772186944;4407.126971037296;4606.378138951199;4606.516315786861;4621.768911554409;4627.172266954534;5098.915156330367;5689.829305120853;5707.486326230576;5712.571557133148;5747.3677895431465;1212.1696637803134;1227.3495047624774;1249.3066348176667;1295.2487557216791;1296.9286689231994;808.8786681997748;810.6068556749972;826.5123577089546;830.7258604660448;876.9342299327576;4268.691763882954;4966.577840883493;5017.322661440732;5049.5158340744765;5060.949390506048;4862.693368509447;5020.799168033238;5221.112410890158;5374.973125134427;5540.504063902724;4479.260921947907;4519.118771839459;4942.7245432701175;5009.5741861941415;5236.607254455931;2625.3567875870535;2650.0062476571284;2908.720163128217;2949.3806300677097;2970.910872673823;5155.484451554815;5195.584353251731;5528.834134975958;5832.334132693828;5921.782128764866;5119.795208191631;5268.262443257947;5435.918461223085;5454.754536045887;5774.884502310014;5514.503694667419;5544;5637.069883469346;5796.536277097822;5873.58884878063;4836.782344794422;4854.660173787801;4878.90242195147;5080.187170052774;5620.4660557247;4954.355933728736;5411.648242864201;5795.768169273182;5826.76692932278;5887.911681324869;4968.354113965162;5939.762409503572;6083.756649734138;6107.877842443215;6141.109539116893;3677.5770786359913;3902.941455878221;3936.2717897189114;3938.566757656713;3977.4033894914837;5336.439673834191;5367.812126575551;5379.65032272901;5529.032419326521;5631.255215281792;4833.178359678793;5582.860428489158;5659.236003139524;5662.85842853932;5674.517665998436;4449.399331090391;5390.51485366319;5615.887682246251;5634.690092044813;5679.432056794288;4621.953780462287;4727.959848833251;5475.370332411823;5480.6437581557075;5490.940897163564;5418.512333889968;5572.551136704405;5640.802571909963;5647.7176141193595;5671.149327600893;4533.750643714628;4752.857414277577;4853.854384368475;4890.682105663121;5570.86072848182 11:24:38 INFO - PROCESS | 1691 | |5;yui.html;649.1982401733858;726.2120599149922;742.4802638153291;751.243411773439;753.5139834806547;12157.452914618842;15129.773053404195;15514.991525550846;15884.364625414999;15904.363825446968;14284;14410.423583056692;14414.49549265776;14430.350634221459;14432.494862679809;721.7113154738121;761.8057395364175;933.4959122074072;933.5378987401245;935.8596210568401;742.6954948471127;750.6396929473856;751.1099347273498;753.5102183580661;754.7433872483355;950.4202436513721;996.9601215951353;1000.4297550396277;1005.3113617172224;1007.4005966449951;978.5987745024541;981.2199301555245;983.4590974963777;984.6061575369838;988.1501908358806;561.3628531616604;698.930106989297;729.1578227147653;730.0764532865929;734.408800915263;14586.92706536471;15053.623659408493;15056.924715376463;15092.396304147931;15110.320035598425;12285.201461905037;12401.689957751129;12531.373431328488;12610.621681349483;12794.616161515169;9176.495292758895;10576.894231057668;10600.840987385196;11012.779744405067;11610.535578576846;3384.289299247161;3963.821628026717;3992.640662340374;4022.7988600569856;4148.191102734938;82874.58562707208;84649;84779;85500;86122.13877861203;8245.381596380295;9477.715668529956;9508.049195080437;9649.807003860024;9835.262355323379;21583.892080539496;21648.89175554112;21651.458713531974;21652.24217152392;21666.783332166793;7159.534630249017;7436.293552112541;7531.397488200931;7677.9232207677205;7849.411294152961;4134.545200027995;4188.32986722123;4193.832246710159;4203.47456567929;4226.915461690749;3462.1690794209208;3474.7915125092577;3602.0994751312173;3609.6209897960334;3611.476335931275;684.2063206680326;694.8436601764563;695.0443140681563;716.6237725194211;718.532953580177;521.3821621378678;530.5834919588104;548.9204065410573;560.2296841842467;603.978860739872;7629.7329593463965;8322.375821813388;8337.124601916712;8358.205970432798;8633.784155396166;572.2817863581179;583.6206465797266;593.6052525070866;593.6319481921237;599.9670018148956;3774.245150969762;3868;3888.105735680809;3931.6461518463475;4032.2300731374735;84176.31647367019;86045.13954860496;96251.51874240584;100342;100772;70096.59806803835;72631.63684181546;76795.46409071787;80471;81324;71590.64204678943;71644.64177679078;72842.27157728355;73037;73697.52604947978;8063.83872322562;8323.916760832315;8380.329573634217;8491.36314776382;8591.312694984512;42957.355639664805;44584.55415445804;44746;44757.55242447534;44877.87805304894;52735.73632131968;57537.41081651252;62344;62785.37214627978;63066;10953.397563133785;11037.448127593749;11181;11296.887031129583;11447.656570302905;12101.092418068503;12373.690657733629;12449.751004980031;12554.246745195318;12789.360531973178;11395.487203076047;11454.255473394203;11652.708682282671;11677.357745324089;11743.823842642196;11115.499802508744;11191.44042797873;11258.324500529994;11269.04213141892;11581.478833452684;11048.613298534512;11425.828612570984;11440.256383335056;11513.424328783694;11619.38563823133;7702.768916932501;9377.296702747457;9427.198688111588;9583.329166958525;9789.804203916025;9447.52762361893;9512.904870951203;9576.616935322787;9618.134367906921;9659.855102173333 11:24:38 INFO - PROCESS | 1691 | __end_tp_report 11:24:38 INFO - PROCESS | 1691 | __start_cc_report 11:24:38 INFO - PROCESS | 1691 | _x_x_mozilla_cycle_collect,1240 11:24:38 INFO - PROCESS | 1691 | __end_cc_report 11:24:38 INFO - PROCESS | 1691 | __startTimestamp1462559078385__endTimestamp 11:24:38 INFO - PROCESS | 1691 | 11:24:38 INFO - PROCESS | 1691 | ------- Summary: start ------- 11:24:38 INFO - PROCESS | 1691 | Number of tests: 6 11:24:38 INFO - PROCESS | 1691 | 11:24:38 INFO - PROCESS | 1691 | [#0] dojo.html Cycles:165 Average:5733.46 Median:5919.11 stddev:646.61 (10.9%) stddev-sans-first:619.09 11:24:38 INFO - PROCESS | 1691 | Values: 3271.9 3455.8 3488.9 3506.4 3535.0 5601.4 5628.7 5631.8 5717.8 5725.3 5577.1 5729.0 5729.1 5737.0 5747.0 5093.0 5170.5 5176.5 5206.9 5235.9 4372.7 5222.5 5238.6 5239.2 5242.7 5165.0 5168.4 5170.5 5186.4 5227.0 5243.4 5260.9 5266.3 5275.4 5320.2 5088.2 5096.2 5101.4 5189.6 5204.8 4677.7 5616.5 5622.4 5652.5 5669.1 6244.7 6272.7 6273.0 6299.5 6313.4 5130.7 6232.7 6316.2 6317.1 6322.2 5870.7 5871.4 5876.3 5882.7 5892.6 6284.5 6289.3 6290.8 6314.6 6314.7 5980.2 6076.9 6079.9 6082.3 6189.8 5639.2 6067.8 6187.5 6193.2 6200.4 6129.5 6163.6 6191.3 6236.2 6240.2 6213.7 6262.3 6290.2 6308.7 6357.8 4826.8 4892.8 4911.6 4914.7 4944.4 4391.6 4443.8 4469.0 4476.1 4487.2 5731.3 5780.6 5818.9 5849.6 5863.6 6229.6 6322.3 6329.3 6356.6 6371.0 5760.1 5763.8 5805.8 5829.6 5851.8 5317.5 5347.0 5371.0 5379.5 5382.8 6296.5 6339.8 6340.0 6344.6 6346.6 6207.8 6243.9 6254.5 6284.6 6286.3 6205.4 6264.7 6317.6 6321.5 6389.1 6210.4 6218.7 6219.4 6228.6 6229.9 6117.9 6265.4 6302.2 6308.0 6318.8 5964.0 5968.9 6121.4 6123.0 6142.3 6038.5 6093.8 6094.9 6095.5 6107.2 5584.3 5789.5 5850.0 5940.5 5941.6 6002.2 6069.5 6074.0 6084.8 6094.7 5893.9 5914.3 5919.1 5923.0 5926.3 11:24:38 INFO - PROCESS | 1691 | 11:24:38 INFO - PROCESS | 1691 | [#1] ext.html Cycles:180 Average:66785.30 Median:37409.22 stddev:139483.31 (372.9%) stddev-sans-first:139785.85 11:24:38 INFO - PROCESS | 1691 | Values: 512.6 525.1 531.1 558.2 559.9 30645.8 32342.4 32431.7 42323.4 43802.8 45012.0 45218.3 45287.5 45364.0 45395.5 9575.0 9797.4 9801.3 9809.5 9816.0 8491.5 8680.4 9578.3 11646.8 11843.3 11774.1 11831.9 11836.0 11854.2 11863.9 8577.8 11762.9 11812.2 11828.3 11845.1 8560.4 8573.3 8576.3 11381.3 11719.6 34349.0 40925.6 41470.6 41610.8 42027.9 3245.6 3296.5 3344.3 3843.9 3930.0 25046.2 28418.4 28701.0 28936.3 29504.0 2720.0 2733.7 2764.8 2827.9 2871.5 432292.0 433624.0 433828.0 434249.0 434921.0 57750.0 60975.8 63535.4 64597.0 64802.4 68958.6 69137.0 69235.6 69277.7 69279.0 16465.6 17073.8 17090.3 17098.4 17107.4 11116.5 11892.4 12142.5 13140.0 13213.9 15308.8 17871.3 18203.1 18316.2 18497.4 6110.4 6785.5 7002.7 7056.8 7108.5 960.3 1032.9 1066.4 1108.4 1163.9 29594.6 29607.3 29997.7 30320.7 30351.4 1067.4 1102.3 1117.3 1160.8 1222.8 15178.0 15282.2 15320.8 15433.5 15730.4 596086.0 603773.0 604733.0 604746.0 606791.0 60094.7 61616.4 61708.4 61710.0 61797.0 1326.3 1374.4 1467.1 1496.1 1505.9 30215.4 30434.5 30656.7 30967.5 31334.7 52211.7 52613.7 53225.7 53280.7 53502.7 516868.0 517900.0 521761.0 521786.0 522726.0 33893.2 35315.6 41677.4 42888.7 44218.7 46623.0 46734.5 46956.3 48231.0 48589.5 36435.1 36882.4 38472.2 39385.4 39414.6 36844.8 37223.8 37594.6 37847.4 38091.4 37130.8 37964.7 38346.6 38460.0 41235.0 28899.0 33172.0 33429.5 33617.5 34601.8 22708.8 23420.9 24716.6 25618.5 26204.0 11:24:38 INFO - PROCESS | 1691 | 11:24:38 INFO - PROCESS | 1691 | [#2] jquery.html Cycles:180 Average:35050.11 Median:21877.88 stddev:95532.84 (436.7%) stddev-sans-first:95765.39 11:24:38 INFO - PROCESS | 1691 | Values: 393.8 398.9 400.4 401.6 425.4 14691.9 19703.8 20387.7 21027.0 21069.4 16491.1 16937.3 17019.7 20167.7 20579.8 5948.5 6162.4 6188.7 6200.8 6210.6 5773.2 6116.4 6123.9 6126.4 6193.0 6029.7 6058.2 6062.2 6073.2 6079.7 5893.8 5947.6 6056.2 6062.5 6063.2 4788.4 5075.0 5271.5 5280.1 5293.2 24666.5 25476.4 25852.5 25934.9 26633.7 2163.3 2241.7 2335.5 2500.7 2829.3 12719.4 14591.8 14847.2 14876.6 15094.5 1671.2 1700.1 1709.6 1760.4 1818.1 119591.0 124461.4 128476.0 129840.3 132403.0 9201.6 11084.5 11439.2 11475.6 11569.2 14221.5 14273.0 14302.2 14313.9 14324.4 7576.1 7657.6 7804.9 8169.4 8976.5 8847.6 8920.7 8926.7 9078.3 9080.5 5642.7 6662.3 6781.7 6798.2 6807.4 1741.9 1762.9 1777.0 1781.5 1878.4 818.8 947.5 978.3 1041.2 1080.8 20886.0 21453.1 21705.5 21830.7 21925.1 945.3 946.4 972.2 1056.8 1125.0 9736.3 11348.4 11523.8 11526.2 11589.7 525522.0 532322.0 532802.0 605717.0 618799.9 147036.3 149852.3 150592.0 156892.0 157291.0 1037.6 1049.8 1090.8 1092.5 1094.3 14933.7 17429.4 17653.6 17830.6 18141.6 38895.8 51997.5 53021.7 53453.0 54192.0 73613.6 74980.6 78050.6 82460.0 89648.0 7241.0 7482.1 7616.7 7850.2 7924.7 12573.2 12827.2 12961.8 13204.6 13253.7 10952.6 11422.8 11434.0 11489.0 11541.1 7602.2 11061.7 11116.3 11338.3 11445.8 6932.3 7014.4 8065.7 8226.6 8529.9 12251.4 12307.4 12439.6 12696.2 12769.0 10088.5 10703.9 12092.3 12136.5 12251.5 11:24:38 INFO - PROCESS | 1691 | 11:24:38 INFO - PROCESS | 1691 | [#3] mootools.html Cycles:180 Average:5702.83 Median:4979.43 stddev:3146.45 (63.2%) stddev-sans-first:3138.86 11:24:38 INFO - PROCESS | 1691 | Values: 1425.2 1593.9 1637.9 1882.5 2002.5 7799.9 8528.3 8828.6 8853.0 8909.0 8566.6 8778.3 8784.7 8786.4 8796.1 2438.8 2466.6 2469.2 2471.9 2474.1 2420.4 2469.6 2472.6 2476.7 2478.0 2412.1 2446.8 2461.5 2463.4 2473.5 2373.9 2397.6 2411.4 2442.8 2450.3 2400.8 2429.2 2431.2 2436.9 2446.3 8517.8 8616.4 8710.4 8715.4 8753.7 5320.1 5428.3 5504.7 5509.0 5509.0 3423.3 3458.9 3515.5 3562.8 3580.3 1041.5 1053.2 1062.3 1062.9 1063.7 11298.4 11385.0 11418.9 11429.7 11454.7 4534.1 4581.4 4652.3 4689.5 4694.1 9007.9 9019.2 9038.9 9039.2 9047.1 5264.9 5272.3 5273.3 5277.2 5278.3 3900.3 4406.9 4729.4 4794.0 4883.4 3638.1 3793.8 3826.4 3905.0 3928.4 1024.3 1104.2 1223.9 1228.0 1232.1 655.3 789.6 880.3 880.3 982.8 5721.8 5776.2 5926.7 6012.1 6151.0 980.7 995.2 998.8 1041.3 1054.9 3868.9 4018.8 4025.5 4064.8 4064.8 10268.4 10624.7 10694.7 10803.3 10859.6 10613.8 10661.3 10758.9 10767.3 10769.7 7688.2 7907.9 8061.8 8179.4 8244.3 5763.6 5882.8 5918.7 5963.6 6083.1 9703.8 9955.0 10036.9 10038.5 10059.6 9710.1 9892.8 10032.9 10146.2 10193.5 8117.3 8322.3 8335.2 8337.7 8347.4 8056.0 8147.7 8189.0 8292.2 8308.4 7395.6 7436.7 7447.1 7577.8 7602.0 7254.2 7348.6 7378.4 7454.4 7506.8 6951.5 6966.9 7027.8 7047.9 7056.3 4930.3 4965.5 4989.2 5047.6 5182.0 4969.7 5015.7 5032.8 5079.7 5245.0 11:24:38 INFO - PROCESS | 1691 | 11:24:38 INFO - PROCESS | 1691 | [#4] prototype.html Cycles:180 Average:4456.24 Median:4960.47 stddev:1376.98 (27.8%) stddev-sans-first:1345.26 11:24:38 INFO - PROCESS | 1691 | Values: 312.6 318.7 325.3 329.2 329.6 5094.2 5329.7 5343.2 5397.4 5397.5 4640.3 5257.0 5313.8 5356.1 5407.9 3852.3 3863.2 4014.8 4069.6 4105.7 3357.4 3389.9 3852.5 4011.0 4029.3 3717.0 3988.7 4012.7 4022.4 4022.9 3062.6 3805.0 4013.7 4034.5 4040.6 3101.5 3241.6 3484.3 3516.0 3537.3 4294.6 4511.3 5079.4 5104.9 5132.1 4291.8 4346.5 4643.2 4647.7 4667.6 4999.5 5115.6 5655.2 5708.9 5711.5 3563.0 3891.7 3910.7 3919.3 3960.4 4937.6 5660.0 5888.3 5902.3 6046.1 3334.4 3612.9 3853.2 3878.6 3901.2 3267.1 3913.0 3956.2 3959.6 3973.5 4407.1 4606.4 4606.5 4621.8 4627.2 5098.9 5689.8 5707.5 5712.6 5747.4 1212.2 1227.3 1249.3 1295.2 1296.9 808.9 810.6 826.5 830.7 876.9 4268.7 4966.6 5017.3 5049.5 5060.9 4862.7 5020.8 5221.1 5375.0 5540.5 4479.3 4519.1 4942.7 5009.6 5236.6 2625.4 2650.0 2908.7 2949.4 2970.9 5155.5 5195.6 5528.8 5832.3 5921.8 5119.8 5268.3 5435.9 5454.8 5774.9 5514.5 5544.0 5637.1 5796.5 5873.6 4836.8 4854.7 4878.9 5080.2 5620.5 4954.4 5411.6 5795.8 5826.8 5887.9 4968.4 5939.8 6083.8 6107.9 6141.1 3677.6 3902.9 3936.3 3938.6 3977.4 5336.4 5367.8 5379.7 5529.0 5631.3 4833.2 5582.9 5659.2 5662.9 5674.5 4449.4 5390.5 5615.9 5634.7 5679.4 4622.0 4728.0 5475.4 5480.6 5490.9 5418.5 5572.6 5640.8 5647.7 5671.1 4533.8 4752.9 4853.9 4890.7 5570.9 11:24:38 INFO - PROCESS | 1691 | 11:24:38 INFO - PROCESS | 1691 | [#5] yui.html Cycles:180 Average:18133.30 Median:29054.85 stddev:25560.91 (88.0%) stddev-sans-first:25598.90 11:24:38 INFO - PROCESS | 1691 | Values: 649.2 726.2 742.5 751.2 753.5 12157.5 15129.8 15515.0 15884.4 15904.4 14284.0 14410.4 14414.5 14430.4 14432.5 721.7 761.8 933.5 933.5 935.9 742.7 750.6 751.1 753.5 754.7 950.4 997.0 1000.4 1005.3 1007.4 978.6 981.2 983.5 984.6 988.2 561.4 698.9 729.2 730.1 734.4 14586.9 15053.6 15056.9 15092.4 15110.3 12285.2 12401.7 12531.4 12610.6 12794.6 9176.5 10576.9 10600.8 11012.8 11610.5 3384.3 3963.8 3992.6 4022.8 4148.2 82874.6 84649.0 84779.0 85500.0 86122.1 8245.4 9477.7 9508.0 9649.8 9835.3 21583.9 21648.9 21651.5 21652.2 21666.8 7159.5 7436.3 7531.4 7677.9 7849.4 4134.5 4188.3 4193.8 4203.5 4226.9 3462.2 3474.8 3602.1 3609.6 3611.5 684.2 694.8 695.0 716.6 718.5 521.4 530.6 548.9 560.2 604.0 7629.7 8322.4 8337.1 8358.2 8633.8 572.3 583.6 593.6 593.6 600.0 3774.2 3868.0 3888.1 3931.6 4032.2 84176.3 86045.1 96251.5 100342.0 100772.0 70096.6 72631.6 76795.5 80471.0 81324.0 71590.6 71644.6 72842.3 73037.0 73697.5 8063.8 8323.9 8380.3 8491.4 8591.3 42957.4 44584.6 44746.0 44757.6 44877.9 52735.7 57537.4 62344.0 62785.4 63066.0 10953.4 11037.4 11181.0 11296.9 11447.7 12101.1 12373.7 12449.8 12554.2 12789.4 11395.5 11454.3 11652.7 11677.4 11743.8 11115.5 11191.4 11258.3 11269.0 11581.5 11048.6 11425.8 11440.3 11513.4 11619.4 7702.8 9377.3 9427.2 9583.3 9789.8 9447.5 9512.9 9576.6 9618.1 9659.9 11:24:38 INFO - PROCESS | 1691 | -------- Summary: end -------- 11:24:38 INFO - PROCESS | 1691 | 11:24:38 INFO - TEST-INFO | 1691: exit 0 11:24:38 INFO - TEST-OK | dromaeo_css | took 1116039ms 11:24:38 INFO - TEST-START | kraken 11:24:38 INFO - operating with platform_type : mac_ 11:24:38 INFO - Initialising browser for kraken test... 11:24:38 INFO - TEST-INFO | started process 1811 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/tmpcJMy_3/profile http://localhost:49273/getInfo.html) 11:24:41 INFO - PROCESS | 1811 | __metrics Screen width/height:1600/1200 11:24:41 INFO - PROCESS | 1811 | colorDepth:24 11:24:41 INFO - PROCESS | 1811 | Browser inner width/height: 1024/689 11:24:41 INFO - PROCESS | 1811 | __metrics 11:24:41 INFO - PROCESS | 1811 | 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" 11:24:41 ERROR - PROCESS | 1811 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:24:41 INFO - TEST-INFO | 1811: exit 0 11:24:41 INFO - Browser initialized. 11:24:41 INFO - Running cycle 1/1 for kraken test... 11:24:41 INFO - TEST-INFO | started process 1813 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/T/tmpcJMy_3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:24:53 INFO - PROCESS | 1813 | RSS: Main: 239177728 11:24:53 INFO - PROCESS | 1813 | 11:24:56 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 11:24:57 INFO - PROCESS | 1813 | RSS: Main: 285978624 11:24:57 INFO - PROCESS | 1813 | 11:24:59 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 11:25:00 INFO - PROCESS | 1813 | RSS: Main: 295026688 11:25:00 INFO - PROCESS | 1813 | 11:25:04 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 11:25:04 INFO - PROCESS | 1813 | RSS: Main: 384770048 11:25:04 INFO - PROCESS | 1813 | 11:25:06 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 11:25:07 INFO - PROCESS | 1813 | RSS: Main: 283480064 11:25:07 INFO - PROCESS | 1813 | 11:25:09 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:25:10 INFO - PROCESS | 1813 | RSS: Main: 287498240 11:25:10 INFO - PROCESS | 1813 | 11:25:12 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 11:25:13 INFO - PROCESS | 1813 | RSS: Main: 323944448 11:25:13 INFO - PROCESS | 1813 | 11:25:16 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 11:25:16 INFO - PROCESS | 1813 | RSS: Main: 327417856 11:25:16 INFO - PROCESS | 1813 | 11:25:19 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 11:25:20 INFO - PROCESS | 1813 | RSS: Main: 325632000 11:25:20 INFO - PROCESS | 1813 | 11:25:21 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:25:22 INFO - PROCESS | 1813 | RSS: Main: 314281984 11:25:22 INFO - PROCESS | 1813 | 11:25:24 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:25:25 INFO - PROCESS | 1813 | RSS: Main: 321568768 11:25:25 INFO - PROCESS | 1813 | 11:25:27 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:25:27 INFO - PROCESS | 1813 | RSS: Main: 323211264 11:25:27 INFO - PROCESS | 1813 | 11:25:30 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:25:31 INFO - PROCESS | 1813 | RSS: Main: 327286784 11:25:31 INFO - PROCESS | 1813 | 11:25:34 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:25:34 INFO - PROCESS | 1813 | RSS: Main: 325013504 11:25:34 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 11:25:36 INFO - PROCESS | 1813 | RSS: Main: 371900416 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | __start_tp_report 11:25:36 INFO - PROCESS | 1813 | _x_x_mozilla_page_load 11:25:36 INFO - PROCESS | 1813 | _x_x_mozilla_page_load_details 11:25:36 INFO - PROCESS | 1813 | |i|pagename|runs| 11:25:36 INFO - PROCESS | 1813 | |0;ai-astar;112;107;112;111;112;111;110;113;111;111 11:25:36 INFO - PROCESS | 1813 | |1;audio-beat-detection;153;117;143;189;146;133;135;116;166;120 11:25:36 INFO - PROCESS | 1813 | |2;audio-dft;185;186;185;184;187;184;180;184;186;185 11:25:36 INFO - PROCESS | 1813 | |3;audio-fft;79;74;58;104;57;62;57;58;111;57 11:25:36 INFO - PROCESS | 1813 | |4;audio-oscillator;93;130;139;109;82;77;102;80;80;99 11:25:36 INFO - PROCESS | 1813 | |5;imaging-gaussian-blur;87;84;87;85;87;84;87;84;88;83 11:25:36 INFO - PROCESS | 1813 | |6;imaging-darkroom;93;93;93;93;93;93;93;105;150;93 11:25:36 INFO - PROCESS | 1813 | |7;imaging-desaturate;87;85;85;84;84;95;116;85;86;87 11:25:36 INFO - PROCESS | 1813 | |8;json-parse-financial;60;59;61;59;57;58;57;77;59;57 11:25:36 INFO - PROCESS | 1813 | |9;json-stringify-tinderbox;65;63;70;78;68;67;66;67;68;68 11:25:36 INFO - PROCESS | 1813 | |10;stanford-crypto-aes;94;106;84;84;88;83;80;84;84;85 11:25:36 INFO - PROCESS | 1813 | |11;stanford-crypto-ccm;140;134;147;146;149;137;134;137;176;143 11:25:36 INFO - PROCESS | 1813 | |12;stanford-crypto-pbkdf2;168;166;224;177;168;170;169;166;166;166 11:25:36 INFO - PROCESS | 1813 | |13;stanford-crypto-sha256-iterative;66;67;67;66;66;64;66;65;71;83 11:25:36 INFO - PROCESS | 1813 | __end_tp_report 11:25:36 INFO - PROCESS | 1813 | __start_cc_report 11:25:36 INFO - PROCESS | 1813 | _x_x_mozilla_cycle_collect,1930 11:25:36 INFO - PROCESS | 1813 | __end_cc_report 11:25:36 INFO - PROCESS | 1813 | __startTimestamp1462559136960__endTimestamp 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | ------- Summary: start ------- 11:25:36 INFO - PROCESS | 1813 | Number of tests: 14 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.50 stddev:1.63 (1.5%) stddev-sans-first:1.69 11:25:36 INFO - PROCESS | 1813 | Values: 112.0 107.0 112.0 111.0 112.0 111.0 110.0 113.0 111.0 111.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:144.50 stddev:23.18 (16.0%) stddev-sans-first:24.23 11:25:36 INFO - PROCESS | 1813 | Values: 153.0 117.0 143.0 189.0 146.0 133.0 135.0 116.0 166.0 120.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:1.90 (1.0%) stddev-sans-first:2.01 11:25:36 INFO - PROCESS | 1813 | Values: 185.0 186.0 185.0 184.0 187.0 184.0 180.0 184.0 186.0 185.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#3] audio-fft Cycles:10 Average:71.70 Median:58.00 stddev:20.45 (35.3%) stddev-sans-first:21.52 11:25:36 INFO - PROCESS | 1813 | Values: 79.0 74.0 58.0 104.0 57.0 62.0 57.0 58.0 111.0 57.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#4] audio-oscillator Cycles:10 Average:99.10 Median:80.00 stddev:21.60 (27.0%) stddev-sans-first:22.80 11:25:36 INFO - PROCESS | 1813 | Values: 93.0 130.0 139.0 109.0 82.0 77.0 102.0 80.0 80.0 99.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:87.00 stddev:1.78 (2.0%) stddev-sans-first:1.81 11:25:36 INFO - PROCESS | 1813 | Values: 87.0 84.0 87.0 85.0 87.0 84.0 87.0 84.0 88.0 83.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#6] imaging-darkroom Cycles:10 Average:99.90 Median:93.00 stddev:18.00 (19.4%) stddev-sans-first:18.92 11:25:36 INFO - PROCESS | 1813 | Values: 93.0 93.0 93.0 93.0 93.0 93.0 93.0 105.0 150.0 93.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#7] imaging-desaturate Cycles:10 Average:89.40 Median:85.50 stddev:9.88 (11.6%) stddev-sans-first:10.44 11:25:36 INFO - PROCESS | 1813 | Values: 87.0 85.0 85.0 84.0 84.0 95.0 116.0 85.0 86.0 87.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:59.00 stddev:5.99 (10.1%) stddev-sans-first:6.35 11:25:36 INFO - PROCESS | 1813 | Values: 60.0 59.0 61.0 59.0 57.0 58.0 57.0 77.0 59.0 57.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:4.00 (5.9%) stddev-sans-first:4.09 11:25:36 INFO - PROCESS | 1813 | Values: 65.0 63.0 70.0 78.0 68.0 67.0 66.0 67.0 68.0 68.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:84.00 stddev:7.57 (9.0%) stddev-sans-first:7.62 11:25:36 INFO - PROCESS | 1813 | Values: 94.0 106.0 84.0 84.0 88.0 83.0 80.0 84.0 84.0 85.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:144.50 stddev:12.37 (8.6%) stddev-sans-first:13.02 11:25:36 INFO - PROCESS | 1813 | Values: 140.0 134.0 147.0 146.0 149.0 137.0 134.0 137.0 176.0 143.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:168.50 stddev:17.88 (10.6%) stddev-sans-first:18.83 11:25:36 INFO - PROCESS | 1813 | Values: 168.0 166.0 224.0 177.0 168.0 170.0 169.0 166.0 166.0 166.0 11:25:36 INFO - PROCESS | 1813 | 11:25:36 INFO - PROCESS | 1813 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:66.50 stddev:5.55 (8.3%) stddev-sans-first:5.83 11:25:36 INFO - PROCESS | 1813 | Values: 66.0 67.0 67.0 66.0 66.0 64.0 66.0 65.0 71.0 83.0 11:25:36 INFO - PROCESS | 1813 | -------- Summary: end -------- 11:25:36 INFO - PROCESS | 1813 | 11:25:37 INFO - TEST-INFO | 1813: exit 0 11:25:37 INFO - TEST-OK | kraken | took 58829ms 11:25:37 INFO - SUITE-END | took 1174s 11:25:38 INFO - Completed test suite (00:19:35) 11:25:38 INFO - javascript benchmark 11:25:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.8691252349904, 3455.844486998085, 3488.8953331400066, 3506.386382383083, 3534.982325088374, 5601.411851755568, 5628.690422026787, 5631.8028868989595, 5717.799877004306, 5725.2843394575675, 5577.107662773957, 5729.0, 5729.054705973509, 5737.0, 5747.0, 5092.999999999991, 5170.508801663836, 5176.456472070435, 5206.921896171561, 5235.921461178085, 4372.73763574185, 5222.503862133091, 5238.60710446718, 5239.161734122541, 5242.659227150242, 5164.974175129119, 5168.379794424674, 5170.482951704818, 5186.377634683843, 5227.0, 5243.370795504526, 5260.894782104375, 5266.289050978107, 5275.393329767049, 5320.228566857822, 5088.211327244283, 5096.159133742928, 5101.362329708786, 5189.636725429222, 5204.817831375922, 4677.666864943475, 5616.494515493625, 5622.437756224385, 5652.491275785158, 5669.121286200645, 6244.718987645521, 6272.717727702265, 6273.0, 6299.496040316763, 6313.40022697843, 5130.743462826844, 6232.719527621268, 6316.242050953869, 6317.1156038154695, 6322.178116844781, 5870.677112758796, 5871.383504732027, 5876.294844618587, 5882.705864706748, 5892.587518873681, 6284.497240220771, 6289.339619339995, 6290.842728931813, 6314.62112273265, 6314.71583778731, 5980.162777211194, 6076.939230607726, 6079.908801367983, 6082.270127584629, 6189.81430557075, 5639.182318563785, 6067.7876274331065, 6187.474064704551, 6193.194884664965, 6200.3799620037635, 6129.511613759063, 6163.599366041193, 6191.25704915413, 6236.158118654014, 6240.219972503437, 6213.65824879627, 6262.3111457739615, 6290.24517057956, 6308.71610777507, 6357.777477788348, 4826.831060912851, 4892.804287828447, 4911.607071434277, 4914.65597408178, 4944.431390390151, 4391.626711729475, 4443.777811109431, 4469.0, 4476.082403107355, 4487.169873573399, 5731.312242530839, 5780.595358324961, 5818.854528636733, 5849.61977471463, 5863.618864773776, 6229.595076320024, 6322.304546499881, 6329.303776584572, 6356.586821856565, 6370.968145159337, 5760.107183386582, 5763.827085187451, 5805.82582522525, 5829.569586935034, 5851.765929362863, 5317.494837990384, 5347.0, 5370.9731451342495, 5379.488948549882, 5382.811601593926, 6296.488324326466, 6339.778107766206, 6339.968300158562, 6344.619322840645, 6346.555741098079, 6207.751689932352, 6243.875122497616, 6254.499640028877, 6284.5600807943, 6286.27707813589, 6205.379462053939, 6264.718087685974, 6317.5893566918, 6321.462675672619, 6389.137466442156, 6210.441060304594, 6218.657973811484, 6219.40915613016, 6228.56400051992, 6229.937700622936, 6117.888303246575, 6265.4361107500545, 6302.212223472066, 6308.0, 6318.842028949313, 5964.045752679551, 5968.895754285471, 6121.387861213843, 6122.969385153046, 6142.262928448615, 6038.486728628116, 6093.756249750138, 6094.878102438015, 6095.542834287535, 6107.236595425572, 5584.274044374205, 5789.478946894799, 5849.970750146222, 5940.495057920125, 5941.554383421347, 6002.219711437485, 6069.514438844793, 6074.0, 6084.817455476343, 6094.664793436226, 5893.9115913260475, 5914.319853216935, 5919.112133180058, 5923.0, 5926.288845338587], "value": 5692.938086304774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.5822454699418, 525.0627629681019, 531.1130412211606, 558.1989844573038, 559.9468050535199, 30645.846770766144, 32342.353152936957, 32431.67568324319, 42323.36514952278, 43802.78098609502, 45012.0, 45218.32172517415, 45287.54712452875, 45364.0, 45395.546044539544, 9574.952125239364, 9797.363171393872, 9801.264905132108, 9809.50952452378, 9815.999999999964, 8491.490510569385, 8680.435771674853, 9578.281628877827, 11646.825297620542, 11843.289402635826, 11774.116941229398, 11831.940840295787, 11836.0, 11854.170208085437, 11863.94068029663, 8577.828443431097, 11762.882371176307, 11812.232204906739, 11828.349440780754, 11845.111616628787, 8560.357973151977, 8573.271271941892, 8576.313894888393, 11381.260218085798, 11719.648410547697, 34349.0, 40925.59074409248, 41470.58529414697, 41610.79194604038, 42027.94930126741, 3245.594300712411, 3296.5384846121324, 3344.3144155448076, 3843.865464708722, 3929.9978505480917, 25046.248612541654, 28418.431631367464, 28701.0, 28936.27659308513, 29504.0, 2720.0, 2733.740294672003, 2764.834109953389, 2827.943441131166, 2871.5261981772983, 432292.0, 433624.0, 433828.0, 434249.0, 434921.0, 57750.0, 60975.78048439007, 63535.36464635387, 64597.0, 64802.35197647963, 68958.62082758416, 69136.96294555586, 69235.61528769498, 69277.65361173263, 69279.0, 16465.588360291087, 17073.829261707473, 17090.316387344617, 17098.435234714892, 17107.401240956508, 11116.499757510932, 11892.405379730979, 12142.514299427923, 13140.01449891243, 13213.867861321263, 15308.846911530742, 17871.28514859391, 18203.08984550767, 18316.17577209029, 18497.445076647455, 6110.388961103854, 6785.457163426914, 7002.719891204295, 7056.7530136444975, 7108.4668649851465, 960.2701946520694, 1032.8999615409564, 1066.3655125200494, 1108.362691452402, 1163.8545181852269, 29594.556081658793, 29607.25981850471, 29997.700022999492, 30320.696793032228, 30351.392972140435, 1067.3928383307302, 1102.3000394749392, 1117.2681893359863, 1160.802663547199, 1222.7798996180595, 15178.0, 15282.159481228638, 15320.770188447183, 15433.5369938902, 15730.370785168694, 596086.0, 603773.0, 604733.0, 604746.0, 606791.0, 60094.69952650209, 61616.38383616106, 61708.38291617027, 61710.0, 61797.0, 1326.2506683723666, 1374.3815283122635, 1467.1343907094865, 1496.132243298884, 1505.894587378873, 30215.395692086477, 30434.543481847348, 30656.693433065382, 30967.535486967263, 31334.68665313409, 52211.73894130505, 52613.7369313151, 53225.7338713304, 53280.73359633177, 53502.66243343947, 516868.0, 517900.0, 521761.0, 521786.0, 522726.0, 33893.152671183416, 35315.646843531234, 41677.374839376826, 42888.71333859989, 44218.683598730866, 46623.0, 46734.53265467438, 46956.295655565875, 48231.0, 48589.51410485991, 36435.089122772144, 36882.44676329911, 38472.230555388174, 39385.40921886231, 39414.605853941095, 36844.81577592095, 37223.81388093042, 37594.624053759115, 37847.43228851625, 38091.42862857004, 37130.8143459281, 37964.67123650659, 38346.616533834305, 38460.03849903663, 41234.9691257721, 28899.0, 33172.004839854846, 33429.49855752117, 33617.495737564444, 34601.82699086489, 22708.772912270666, 23420.946057427114, 24716.629250560898, 25618.487630247662, 26204.0], "value": 20320.89635751862, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.8227797491129, 398.8584052661303, 400.40339893558604, 401.6104378752609, 425.37469919218756, 14691.853081469182, 19703.802961970396, 20387.694184587242, 21026.99999999998, 21069.367918962416, 16491.09298988555, 16937.3225070997, 17019.659606807858, 20167.697484537744, 20579.794202057976, 5948.464638182562, 6162.352952939944, 6188.659623720693, 6200.78297259596, 6210.565260431772, 5773.191753154561, 6116.388361163892, 6123.908141377883, 6126.356732543063, 6193.0, 6029.668368239745, 6058.182145410357, 6062.181605483247, 6073.210482637273, 6079.726412311456, 5893.823185304448, 5947.6134051286745, 6056.242969628796, 6062.45407421107, 6063.181470501492, 4788.353572267734, 5074.974625126888, 5271.499207575274, 5280.128778751501, 5293.23248129023, 24666.50666986668, 25476.36309092269, 25852.482950340887, 25934.87032564844, 26633.73366266332, 2163.2536774812665, 2241.68616393705, 2335.497867958397, 2500.6749122614124, 2829.349249672566, 12719.364031798374, 14591.78112328316, 14847.183404912725, 14876.553703388916, 15094.471693490676, 1671.2479384277124, 1700.1159397113431, 1709.5640611644076, 1760.4014635024025, 1818.1272988965238, 119591.0, 124461.37769311186, 128476.0, 129840.31437170606, 132403.0, 9201.63193472267, 11084.501197446134, 11439.199256052163, 11475.598354057567, 11569.19015668912, 14221.502247421291, 14273.0, 14302.213378264296, 14313.928430357782, 14324.427022919175, 7576.090869095744, 7657.592371394676, 7804.921950780533, 8169.428140030141, 8976.506292153994, 8847.601857916408, 8920.73237802867, 8926.68756593516, 9078.273738100936, 9080.500572468578, 5642.6614403135945, 6662.333766623299, 6781.6609169541325, 6798.2182049064, 6807.3873351398615, 1741.8503787500442, 1762.9069976614326, 1777.0496542751775, 1781.4744650328184, 1878.3519685708409, 818.8157664525434, 947.5262368815593, 978.2663002747939, 1041.1774697988503, 1080.8432777247256, 20885.95570221483, 21453.141874325163, 21705.457363566038, 21830.672539911913, 21925.08316633766, 945.281585994649, 946.432140715579, 972.1930797438109, 1056.841473778924, 1124.966251012471, 9736.269779766546, 11348.375839328752, 11523.827142592867, 11526.193166478433, 11589.710257243636, 525522.0, 532322.0, 532802.0, 605717.0, 618799.9060004671, 147036.26481867523, 149852.2507387456, 150592.0, 156892.0, 157291.0, 1037.5694086953981, 1049.821530339844, 1090.7709381029913, 1092.5029111754134, 1094.2941802537318, 14933.701325973638, 17429.38997135094, 17653.574422224967, 17830.5542361442, 18141.637167256846, 38895.805520972215, 51997.480025199264, 53021.73489132529, 53453.0, 54192.0, 73613.63193183999, 74980.62509687417, 78050.6097469509, 82460.0, 89648.0, 7241.0, 7482.139553951365, 7616.69533218665, 7850.175731548334, 7924.683012679428, 12573.245605263714, 12827.166234194747, 12961.805572916603, 13204.60386188416, 13253.734925301633, 10952.56189752401, 11422.828657569977, 11434.0, 11489.0, 11541.134414919083, 7602.163761986288, 11061.668149955514, 11116.333020018825, 11338.319700817978, 11445.828312575484, 6932.341427564374, 7014.403775679124, 8065.717699880476, 8226.588670566567, 8529.872051919103, 12251.387430628254, 12307.446164922421, 12439.574283014725, 12696.238225706487, 12769.0, 10088.54601542947, 10703.892961070289, 12092.274463532216, 12136.453859576517, 12251.509939602316], "value": 9815.15329188505, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.1947649577994, 1593.9123348215844, 1637.9181040947954, 1882.463497903098, 2002.539415934336, 7799.92200077999, 8528.317734581235, 8828.646854125827, 8853.0, 8909.0, 8566.57167141642, 8778.253848422875, 8784.692535761249, 8786.384953053288, 8796.076411976746, 2438.817088718344, 2466.6176742604925, 2469.1604854349516, 2471.9258422247362, 2474.1216868011866, 2420.4190994161363, 2469.592517234654, 2472.6414669872856, 2476.702795664523, 2478.033566908907, 2412.1316326122583, 2446.779789818916, 2461.520003599299, 2463.4087818923417, 2473.530029294437, 2373.9406514837096, 2397.5804234258935, 2411.3730430088126, 2442.829001969845, 2450.2649205238536, 2400.840244542879, 2429.1740808125146, 2431.234161239204, 2436.8537887726616, 2446.266120163944, 8517.787055323604, 8616.396852220347, 8710.390272680916, 8715.389922705413, 8753.693620723308, 5320.095583750733, 5428.294321738149, 5504.669719816824, 5508.9724551377385, 5509.0, 3423.2639982403653, 3458.8789392371145, 3515.542979412686, 3562.7684200526883, 3580.3197392495345, 1041.510490069666, 1053.1890444357878, 1062.2988827373897, 1062.8883967183413, 1063.6596289187464, 11298.378589177591, 11384.975352218256, 11418.942905285503, 11429.714257143554, 11454.656360309204, 4534.13851368239, 4581.427321584802, 4652.278896771006, 4689.531046895283, 4694.108119457293, 9007.909920900838, 9019.188273055457, 9038.909610903938, 9039.231665308522, 9047.095290470901, 5264.894702105937, 5272.261883336336, 5273.314469119066, 5277.208418737144, 5278.340207474066, 3900.297946369624, 4406.867793966187, 4729.36153619257, 4793.976030119897, 4883.438404583448, 3638.0541059324764, 3793.7534060285993, 3826.426036094608, 3905.0, 3928.4303775952326, 1024.3341827811832, 1104.2380757277454, 1223.9020878329711, 1227.9508819647115, 1232.1128787273328, 655.2824657000576, 789.6249281591313, 880.2869675562814, 880.335346813152, 982.7985263021063, 5721.76981948883, 5776.249087618583, 5926.674032928144, 6012.128241405059, 6151.0, 980.7008862296925, 995.1938929467154, 998.8152191844532, 1041.2555023158477, 1054.9472526373804, 3868.941965870514, 4018.8392464301687, 4025.5169379674626, 4064.7967601620394, 4064.837406503707, 10268.383896966057, 10624.734381640521, 10694.732631684115, 10803.297785643908, 10859.619913302997, 10613.787724245472, 10661.253712240068, 10758.94620526908, 10767.300125491818, 10769.730756730987, 7688.231176882379, 7907.920920790833, 8061.798455038554, 8179.427440079137, 8244.340452763765, 5763.625364351471, 5882.823515294548, 5918.674472904032, 5963.552733545088, 6083.087536869328, 9703.757406064904, 9955.0, 10036.94981525117, 10038.498075096362, 10059.647912323033, 9710.077542633438, 9892.752681183028, 10032.899671003488, 10146.239032072477, 10193.541290642088, 8117.269445749911, 8322.292605128632, 8335.166483351617, 8337.749867503984, 8347.373946953881, 8055.959720201361, 8147.674093036212, 8188.976377952756, 8292.212239837207, 8308.418410711192, 7395.630218489162, 7436.665350059152, 7447.106347238366, 7577.772666819784, 7601.961990190013, 7254.238304977901, 7348.632568371667, 7378.372838308804, 7454.366378857858, 7506.812329691801, 6951.478639101986, 6966.895496567657, 7027.789166325018, 7047.859042819217, 7056.294370562902, 4930.309756634002, 4965.45380008206, 4989.151844186569, 5047.646664733434, 5182.0, 4969.701817890938, 5015.699058056528, 5032.823851165192, 5079.6952182869145, 5245.0], "value": 4600.66678497477, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.62485017978423, 318.666992992323, 325.25516567061425, 329.16884865714053, 329.58143158189085, 5094.235864620309, 5329.748853199545, 5343.198520221959, 5397.4062853086125, 5397.487238712326, 4640.257558790594, 5257.0, 5313.814016509423, 5356.071814146904, 5407.864803379917, 3852.3226357681424, 3863.2080423513253, 4014.7791871447057, 4069.5786007325596, 4105.7331273467125, 3357.446021406465, 3389.8983030509125, 3852.5376954765466, 4010.95715114071, 4029.2747305485, 3716.981415092934, 3988.6809055275507, 4012.6589239914642, 4022.3966405039187, 4022.919541609166, 3062.6171728533823, 3804.9619503804884, 4013.695548946604, 4034.5072323240297, 4040.5555388907196, 3101.4727496325454, 3241.578594782663, 3484.3205574912904, 3516.0, 3537.2748586539697, 4294.592013758688, 4511.263163681973, 5079.4412614612365, 5104.872378190538, 5132.101882170605, 4291.828326866922, 4346.478422589278, 4643.210654188762, 4647.721136731807, 4667.649926255544, 4999.450060493343, 5115.61632877532, 5655.208270842086, 5708.942910570882, 5711.514521265697, 3562.964370356315, 3891.6886649068006, 3910.65082546521, 3919.2945269851416, 3960.3663413853646, 4937.604991600663, 5660.0, 5888.322842873082, 5902.321233058167, 6046.062860256668, 3334.3664703706218, 3612.8735494257576, 3853.2293541291856, 3878.5733569307354, 3901.1612503311926, 3267.0852161394373, 3912.9608703912595, 3956.208758248362, 3959.6436320731273, 3973.5231772186944, 4407.126971037296, 4606.378138951199, 4606.516315786861, 4621.768911554409, 4627.172266954534, 5098.915156330367, 5689.829305120853, 5707.486326230576, 5712.571557133148, 5747.3677895431465, 1212.1696637803134, 1227.3495047624774, 1249.3066348176667, 1295.2487557216791, 1296.9286689231994, 808.8786681997748, 810.6068556749972, 826.5123577089546, 830.7258604660448, 876.9342299327576, 4268.691763882954, 4966.577840883493, 5017.322661440732, 5049.5158340744765, 5060.949390506048, 4862.693368509447, 5020.799168033238, 5221.112410890158, 5374.973125134427, 5540.504063902724, 4479.260921947907, 4519.118771839459, 4942.7245432701175, 5009.5741861941415, 5236.607254455931, 2625.3567875870535, 2650.0062476571284, 2908.720163128217, 2949.3806300677097, 2970.910872673823, 5155.484451554815, 5195.584353251731, 5528.834134975958, 5832.334132693828, 5921.782128764866, 5119.795208191631, 5268.262443257947, 5435.918461223085, 5454.754536045887, 5774.884502310014, 5514.503694667419, 5544.0, 5637.069883469346, 5796.536277097822, 5873.58884878063, 4836.782344794422, 4854.660173787801, 4878.90242195147, 5080.187170052774, 5620.4660557247, 4954.355933728736, 5411.648242864201, 5795.768169273182, 5826.76692932278, 5887.911681324869, 4968.354113965162, 5939.762409503572, 6083.756649734138, 6107.877842443215, 6141.109539116893, 3677.5770786359913, 3902.941455878221, 3936.2717897189114, 3938.566757656713, 3977.4033894914837, 5336.439673834191, 5367.812126575551, 5379.65032272901, 5529.032419326521, 5631.255215281792, 4833.178359678793, 5582.860428489158, 5659.236003139524, 5662.85842853932, 5674.517665998436, 4449.399331090391, 5390.51485366319, 5615.887682246251, 5634.690092044813, 5679.432056794288, 4621.953780462287, 4727.959848833251, 5475.370332411823, 5480.6437581557075, 5490.940897163564, 5418.512333889968, 5572.551136704405, 5640.802571909963, 5647.7176141193595, 5671.149327600893, 4533.750643714628, 4752.857414277577, 4853.854384368475, 4890.682105663121, 5570.86072848182], "value": 4019.5148009709314, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.1982401733858, 726.2120599149922, 742.4802638153291, 751.243411773439, 753.5139834806547, 12157.452914618842, 15129.773053404195, 15514.991525550846, 15884.364625414999, 15904.363825446968, 14284.0, 14410.423583056692, 14414.49549265776, 14430.350634221459, 14432.494862679809, 721.7113154738121, 761.8057395364175, 933.4959122074072, 933.5378987401245, 935.8596210568401, 742.6954948471127, 750.6396929473856, 751.1099347273498, 753.5102183580661, 754.7433872483355, 950.4202436513721, 996.9601215951353, 1000.4297550396277, 1005.3113617172224, 1007.4005966449951, 978.5987745024541, 981.2199301555245, 983.4590974963777, 984.6061575369838, 988.1501908358806, 561.3628531616604, 698.930106989297, 729.1578227147653, 730.0764532865929, 734.408800915263, 14586.92706536471, 15053.623659408493, 15056.924715376463, 15092.396304147931, 15110.320035598425, 12285.201461905037, 12401.689957751129, 12531.373431328488, 12610.621681349483, 12794.616161515169, 9176.495292758895, 10576.894231057668, 10600.840987385196, 11012.779744405067, 11610.535578576846, 3384.289299247161, 3963.821628026717, 3992.640662340374, 4022.7988600569856, 4148.191102734938, 82874.58562707208, 84649.0, 84779.0, 85500.0, 86122.13877861203, 8245.381596380295, 9477.715668529956, 9508.049195080437, 9649.807003860024, 9835.262355323379, 21583.892080539496, 21648.89175554112, 21651.458713531974, 21652.24217152392, 21666.783332166793, 7159.534630249017, 7436.293552112541, 7531.397488200931, 7677.9232207677205, 7849.411294152961, 4134.545200027995, 4188.32986722123, 4193.832246710159, 4203.47456567929, 4226.915461690749, 3462.1690794209208, 3474.7915125092577, 3602.0994751312173, 3609.6209897960334, 3611.476335931275, 684.2063206680326, 694.8436601764563, 695.0443140681563, 716.6237725194211, 718.532953580177, 521.3821621378678, 530.5834919588104, 548.9204065410573, 560.2296841842467, 603.978860739872, 7629.7329593463965, 8322.375821813388, 8337.124601916712, 8358.205970432798, 8633.784155396166, 572.2817863581179, 583.6206465797266, 593.6052525070866, 593.6319481921237, 599.9670018148956, 3774.245150969762, 3868.0, 3888.105735680809, 3931.6461518463475, 4032.2300731374735, 84176.31647367019, 86045.13954860496, 96251.51874240584, 100342.0, 100772.0, 70096.59806803835, 72631.63684181546, 76795.46409071787, 80471.0, 81324.0, 71590.64204678943, 71644.64177679078, 72842.27157728355, 73037.0, 73697.52604947978, 8063.83872322562, 8323.916760832315, 8380.329573634217, 8491.36314776382, 8591.312694984512, 42957.355639664805, 44584.55415445804, 44746.0, 44757.55242447534, 44877.87805304894, 52735.73632131968, 57537.41081651252, 62344.0, 62785.37214627978, 63066.0, 10953.397563133785, 11037.448127593749, 11181.0, 11296.887031129583, 11447.656570302905, 12101.092418068503, 12373.690657733629, 12449.751004980031, 12554.246745195318, 12789.360531973178, 11395.487203076047, 11454.255473394203, 11652.708682282671, 11677.357745324089, 11743.823842642196, 11115.499802508744, 11191.44042797873, 11258.324500529994, 11269.04213141892, 11581.478833452684, 11048.613298534512, 11425.828612570984, 11440.256383335056, 11513.424328783694, 11619.38563823133, 7702.768916932501, 9377.296702747457, 9427.198688111588, 9583.329166958525, 9789.804203916025, 9447.52762361893, 9512.904870951203, 9576.616935322787, 9618.134367906921, 9659.855102173333], "value": 6897.800244200785, "unit": "score"}], "name": "dromaeo_css", "value": 7246.943254836228, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 112.0, 111.0, 112.0, 111.0, 110.0, 113.0, 111.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 117.0, 143.0, 189.0, 146.0, 133.0, 135.0, 116.0, 166.0, 120.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 185.0, 184.0, 187.0, 184.0, 180.0, 184.0, 186.0, 185.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 58.0, 104.0, 57.0, 62.0, 57.0, 58.0, 111.0, 57.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 130.0, 139.0, 109.0, 82.0, 77.0, 102.0, 80.0, 80.0, 99.0], "value": 99.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 85.0, 87.0, 84.0, 87.0, 84.0, 88.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 105.0, 150.0, 93.0], "value": 99.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 84.0, 84.0, 95.0, 116.0, 85.0, 86.0, 87.0], "value": 89.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 61.0, 59.0, 57.0, 58.0, 57.0, 77.0, 59.0, 57.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 70.0, 78.0, 68.0, 67.0, 66.0, 67.0, 68.0, 68.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 106.0, 84.0, 84.0, 88.0, 83.0, 80.0, 84.0, 84.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 134.0, 147.0, 146.0, 149.0, 137.0, 134.0, 137.0, 176.0, 143.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 224.0, 177.0, 168.0, 170.0, 169.0, 166.0, 166.0, 166.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 66.0, 64.0, 66.0, 65.0, 71.0, 83.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1485.0999999999997, "alertThreshold": 2.0}]} 11:25:38 INFO - Return code: 0 11:25:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:25:38 ERROR - # TBPL SUCCESS # 11:25:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:25:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:25:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:25:38 INFO - 2016-05-06 11:25:38.256 screenresolution[1823:10080] starting screenresolution argv=screenresolution get 11:25:38 INFO - 2016-05-06 11:25:38.258 screenresolution[1823:10080] Display 0: 1600x1200x32@60 11:25:38 INFO - 2016-05-06 11:25:38.264 screenresolution[1824:10083] starting screenresolution argv=screenresolution list 11:25:38 INFO - Available Modes on Display 0 11:25:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:25:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:25:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:25:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:25:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:25:38 INFO - Intel Iris: 11:25:38 INFO - Chipset Model: Intel Iris 11:25:38 INFO - Type: GPU 11:25:38 INFO - Bus: Built-In 11:25:38 INFO - VRAM (Dynamic, Max): 1536 MB 11:25:38 INFO - Vendor: Intel (0x8086) 11:25:38 INFO - Device ID: 0x0a2e 11:25:38 INFO - Revision ID: 0x0009 11:25:38 INFO - Displays: 11:25:38 INFO - Display: 11:25:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:25:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:25:38 INFO - Display Serial Number: 1600x1200 60 11:25:38 INFO - Main Display: Yes 11:25:38 INFO - Mirror: Off 11:25:38 INFO - Online: Yes 11:25:38 INFO - Rotation: Supported 11:25:38 INFO - Return code: 0 11:25:38 INFO - Running post-action listener: _resource_record_post_action 11:25:38 INFO - ##### 11:25:38 INFO - ##### Finished run-tests step (success) 11:25:38 INFO - ##### 11:25:38 INFO - Running post-run listener: _resource_record_post_run 11:25:39 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31940096; Write bytes: 699830784; Read time: 325; Write time: 2158 11:25:39 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375896576; Write bytes: 369796608; Read time: 28542; Write time: 734 11:25:39 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 31014400; Write bytes: 300907520; Read time: 311; Write time: 1366 11:25:39 INFO - Running post-run listener: _upload_blobber_files 11:25:39 INFO - Blob upload gear active. 11:25:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:25:39 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 11:25:39 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'] 11:25:39 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 11:25:39 INFO - (blobuploader) - INFO - Open directory for files ... 11:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:40 INFO - (blobuploader) - INFO - Done attempting. 11:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:25:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:41 INFO - (blobuploader) - INFO - Done attempting. 11:25:41 INFO - (blobuploader) - INFO - Iteration through files over. 11:25:41 INFO - Return code: 0 11:25:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:25:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:25:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d68f92387843eeea0b6cb3207b59a7340ae8dd4d4f8ff3e51a330027a1bc9ee3028a14eae62d5af1de53e715896cc6e3249f006cfccb014628c18460ca188c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3511c83b7bbba8150ce72a5da6e60a39d710be91ba86dc4528c6a6fab54baa2748a32ddd894eb2c5d657a673e1479d111548bfa43c85f2e0ed574d12053932e"} 11:25:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:25:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:25:41 INFO - Contents: 11:25:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1d68f92387843eeea0b6cb3207b59a7340ae8dd4d4f8ff3e51a330027a1bc9ee3028a14eae62d5af1de53e715896cc6e3249f006cfccb014628c18460ca188c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3511c83b7bbba8150ce72a5da6e60a39d710be91ba86dc4528c6a6fab54baa2748a32ddd894eb2c5d657a673e1479d111548bfa43c85f2e0ed574d12053932e"} 11:25:41 INFO - Running post-run listener: copy_logs_to_upload_dir 11:25:41 INFO - Copying logs to upload dir... 11:25:41 INFO - mkdir: /builds/slave/test/build/upload/logs 11:25:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.143098 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-05-06 11:25:41.874609) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 11:25:41.878346) ========= 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.rHvEWjKZYa/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.SNpmrWduZW/Listeners TMPDIR=/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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/1d68f92387843eeea0b6cb3207b59a7340ae8dd4d4f8ff3e51a330027a1bc9ee3028a14eae62d5af1de53e715896cc6e3249f006cfccb014628c18460ca188c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3511c83b7bbba8150ce72a5da6e60a39d710be91ba86dc4528c6a6fab54baa2748a32ddd894eb2c5d657a673e1479d111548bfa43c85f2e0ed574d12053932e"} build_url:https://queue.taskcluster.net/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010987 build_url: 'https://queue.taskcluster.net/v1/task/fxUHBXiKTYeaF7hOB5Zvqg/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/1d68f92387843eeea0b6cb3207b59a7340ae8dd4d4f8ff3e51a330027a1bc9ee3028a14eae62d5af1de53e715896cc6e3249f006cfccb014628c18460ca188c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3511c83b7bbba8150ce72a5da6e60a39d710be91ba86dc4528c6a6fab54baa2748a32ddd894eb2c5d657a673e1479d111548bfa43c85f2e0ed574d12053932e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 11:25:41.907048) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 11:25:41.907388) ========= 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.rHvEWjKZYa/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.SNpmrWduZW/Listeners TMPDIR=/var/folders/s6/fh_2vd2n39xc3pp5fgw6gd3h00000w/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.005037 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 11:25:41.956567) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 11:25:41.957055) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 11:25:41.957559) ========= ========= Total master_lag: 0.18 =========