builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0181 starttime: 1463065291.39 results: success (0) buildid: 20160512060851 builduid: 972a775933a04a02960ab905716bfdbb revision: 1e5b69b0387beb39a0b997938c07a6a05d4ae42a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:31.388842) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:31.389237) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:31.389499) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:31.408649) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:31.409315) ========= 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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-12 08:01:31-- 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% 13.1M=0.001s 2016-05-12 08:01:31 (13.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.078789 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:31.507189) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:31.507509) ========= 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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.026724 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:31.555387) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-12 08:01:31.555737) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1e5b69b0387beb39a0b997938c07a6a05d4ae42a --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 1e5b69b0387beb39a0b997938c07a6a05d4ae42a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xKHrZIwcDj/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.9SCcPYdvL2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-12 08:01:31,625 truncating revision to first 12 chars 2016-05-12 08:01:31,625 Setting DEBUG logging. 2016-05-12 08:01:31,626 attempt 1/10 2016-05-12 08:01:31,626 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1e5b69b0387b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 08:01:32,697 unpacking tar archive at: fx-team-1e5b69b0387b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.307600 ========= master_lag: 2.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-12 08:01:34.888441) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:34.888807) ========= script_repo_revision: 1e5b69b0387beb39a0b997938c07a6a05d4ae42a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:34.889170) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:34.889423) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 08:01:34.903897) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-12 08:01:34.904161) ========= /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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:01:35 INFO - MultiFileLogger online at 20160512 08:01:35 in /builds/slave/test 08:01:35 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 08:01:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:01:35 INFO - {'append_to_log': False, 08:01:35 INFO - 'base_work_dir': '/builds/slave/test', 08:01:35 INFO - 'blob_upload_branch': 'Fx-Team', 08:01:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:01:35 INFO - 'branch': 'Fx-Team', 08:01:35 INFO - 'buildbot_json_path': 'buildprops.json', 08:01:35 INFO - 'config_files': ('talos/mac_config.py',), 08:01:35 INFO - 'default_actions': ('clobber', 08:01:35 INFO - 'read-buildbot-config', 08:01:35 INFO - 'download-and-extract', 08:01:35 INFO - 'populate-webroot', 08:01:35 INFO - 'create-virtualenv', 08:01:35 INFO - 'install', 08:01:35 INFO - 'run-tests'), 08:01:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:01:35 INFO - 'download_minidump_stackwalk': True, 08:01:35 INFO - 'download_symbols': 'ondemand', 08:01:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:01:35 INFO - 'tooltool.py': '/tools/tooltool.py', 08:01:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:01:35 INFO - '/tools/misc-python/virtualenv.py')}, 08:01:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:01:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:01:35 INFO - 'installer_path': 'installer.exe', 08:01:35 INFO - 'log_level': 'info', 08:01:35 INFO - 'log_name': 'talos', 08:01:35 INFO - 'log_to_console': True, 08:01:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:01:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:01:35 INFO - 'opt_config_files': (), 08:01:35 INFO - 'pip_index': False, 08:01:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:01:35 INFO - 'cmd': ('bash', 08:01:35 INFO - '-c', 08:01:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:01:35 INFO - 'enabled': True, 08:01:35 INFO - 'halt_on_failure': False, 08:01:35 INFO - 'name': 'check_screen_resolution'},), 08:01:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:01:35 INFO - 'cmd': ('bash', 08:01:35 INFO - '-c', 08:01:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:01:35 INFO - 'enabled': True, 08:01:35 INFO - 'halt_on_failure': False, 08:01:35 INFO - 'name': 'check_screen_resolution'},), 08:01:35 INFO - 'run_cmd_checks_enabled': True, 08:01:35 INFO - 'sps_profile': False, 08:01:35 INFO - 'sps_profile_interval': 0, 08:01:35 INFO - 'suite': 'dromaeojs', 08:01:35 INFO - 'system_bits': '32', 08:01:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:01:35 INFO - 'title': 't-yosemite-r7-0181', 08:01:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:01:35 INFO - 'use_talos_json': True, 08:01:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:01:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:01:35 INFO - 'work_dir': 'build'} 08:01:35 INFO - ##### 08:01:35 INFO - ##### Running clobber step. 08:01:35 INFO - ##### 08:01:35 INFO - Running pre-action listener: _resource_record_pre_action 08:01:35 INFO - Running main action method: clobber 08:01:35 INFO - rmtree: /builds/slave/test/build 08:01:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:01:36 INFO - Running post-action listener: _resource_record_post_action 08:01:36 INFO - ##### 08:01:36 INFO - ##### Finished clobber step (success) 08:01:36 INFO - ##### 08:01:36 INFO - ##### 08:01:36 INFO - ##### Running read-buildbot-config step. 08:01:36 INFO - ##### 08:01:36 INFO - Running pre-action listener: _resource_record_pre_action 08:01:36 INFO - Running main action method: read_buildbot_config 08:01:36 INFO - Using buildbot properties: 08:01:36 INFO - { 08:01:36 INFO - "project": "", 08:01:36 INFO - "product": "firefox", 08:01:36 INFO - "script_repo_revision": "production", 08:01:36 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:01:36 INFO - "repository": "", 08:01:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:01:36 INFO - "buildid": "20160512060851", 08:01:36 INFO - "pgo_build": "False", 08:01:36 INFO - "basedir": "/builds/slave/test", 08:01:36 INFO - "buildnumber": 11, 08:01:36 INFO - "slavename": "t-yosemite-r7-0181", 08:01:36 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:01:36 INFO - "platform": "yosemite_r7", 08:01:36 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:01:36 INFO - "branch": "fx-team", 08:01:36 INFO - "repo_path": "integration/fx-team", 08:01:36 INFO - "revision": "1e5b69b0387beb39a0b997938c07a6a05d4ae42a", 08:01:36 INFO - "stage_platform": "macosx64", 08:01:36 INFO - "builduid": "972a775933a04a02960ab905716bfdbb", 08:01:36 INFO - "slavebuilddir": "test" 08:01:36 INFO - } 08:01:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:01:36 INFO - Running post-action listener: _resource_record_post_action 08:01:36 INFO - ##### 08:01:36 INFO - ##### Finished read-buildbot-config step (success) 08:01:36 INFO - ##### 08:01:36 INFO - ##### 08:01:36 INFO - ##### Running download-and-extract step. 08:01:36 INFO - ##### 08:01:36 INFO - Running pre-action listener: _resource_record_pre_action 08:01:36 INFO - Running main action method: download_and_extract 08:01:36 INFO - mkdir: /builds/slave/test/build/tests 08:01:36 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')]} 08:01:36 INFO - https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:01:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:01:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:01:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:01:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:01:38 INFO - Downloaded 1199 bytes. 08:01:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:01:38 INFO - Using the following test package requirements: 08:01:38 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:01:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:01:38 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:01:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:01:38 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:01:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:01:38 INFO - u'jsshell-mac.zip'], 08:01:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:01:38 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:01:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:01:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:01:38 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:01:38 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:01:38 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:01:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:01:38 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:01:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:01:38 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:01:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:38 INFO - https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:01:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:01:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:01:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:01:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:01:39 INFO - Downloaded 18151124 bytes. 08:01: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'] 08:01: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 08:01:42 INFO - Return code: 0 08:01: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 08:01: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')]} 08:01:42 INFO - https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:01:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:01:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:01:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:01:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:01:43 INFO - Downloaded 18151124 bytes. 08:01: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'] 08:01: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 08:01:47 INFO - Return code: 0 08:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:47 INFO - https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:01:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:01:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:01:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:01:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:01:48 INFO - Downloaded 10840239 bytes. 08:01: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'] 08:01: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 08:01:48 INFO - Return code: 0 08:01: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')]} 08:01:48 INFO - https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:01:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:01:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:01:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:01:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:01:51 INFO - Downloaded 109819449 bytes. 08:01:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:01:51 INFO - mkdir: /builds/slave/test/properties 08:01:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:01:51 INFO - Writing to file /builds/slave/test/properties/build_url 08:01:51 INFO - Contents: 08:01:51 INFO - build_url:https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:01:52 INFO - Running post-action listener: _resource_record_post_action 08:01:52 INFO - Running post-action listener: set_extra_try_arguments 08:01:52 INFO - ##### 08:01:52 INFO - ##### Finished download-and-extract step (success) 08:01:52 INFO - ##### 08:01:52 INFO - ##### 08:01:52 INFO - ##### Running populate-webroot step. 08:01:52 INFO - ##### 08:01:52 INFO - Running pre-action listener: _resource_record_pre_action 08:01:52 INFO - Running main action method: populate_webroot 08:01:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:01:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:01:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:01:52 INFO - '--tppagecycles', 08:01:52 INFO - '7'], 08:01:52 INFO - 'tests': ['tsvgm']}}, 08:01:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:01:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:01:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:01:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:01:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:01:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:01:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:01:52 INFO - 'talos_options': ['--disable-e10s'], 08:01:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:01:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:01:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:01:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:01:52 INFO - 'talos_options': ['--disable-e10s'], 08:01:52 INFO - 'tests': ['damp', 'tps']}, 08:01:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:01:52 INFO - 'tests': ['damp', 'tps']}, 08:01:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:01:52 INFO - 'tests': ['dromaeo_dom']}, 08:01:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:01:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:01:52 INFO - 'tests': ['a11yr', 08:01:52 INFO - 'ts_paint', 08:01:52 INFO - 'tpaint', 08:01:52 INFO - 'sessionrestore', 08:01:52 INFO - 'sessionrestore_no_auto_restore', 08:01:52 INFO - 'tabpaint']}, 08:01:52 INFO - 'other-e10s': {'tests': ['a11yr', 08:01:52 INFO - 'ts_paint', 08:01:52 INFO - 'tpaint', 08:01:52 INFO - 'sessionrestore', 08:01:52 INFO - 'sessionrestore_no_auto_restore', 08:01:52 INFO - 'tabpaint']}, 08:01:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:01:52 INFO - 'ts_paint', 08:01:52 INFO - 'tpaint', 08:01:52 INFO - 'sessionrestore', 08:01:52 INFO - 'sessionrestore_no_auto_restore', 08:01:52 INFO - 'tabpaint']}, 08:01:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:01:52 INFO - 'ts_paint', 08:01:52 INFO - 'tpaint', 08:01:52 INFO - 'sessionrestore', 08:01:52 INFO - 'sessionrestore_no_auto_restore', 08:01:52 INFO - 'tabpaint']}, 08:01:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:01:52 INFO - 'tests': ['a11yr', 08:01:52 INFO - 'ts_paint', 08:01:52 INFO - 'tpaint', 08:01:52 INFO - 'sessionrestore', 08:01:52 INFO - 'sessionrestore_no_auto_restore', 08:01:52 INFO - 'tabpaint']}, 08:01:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:01:52 INFO - 'tests': ['a11yr', 08:01:52 INFO - 'ts_paint', 08:01:52 INFO - 'tpaint', 08:01:52 INFO - 'sessionrestore', 08:01:52 INFO - 'sessionrestore_no_auto_restore', 08:01:52 INFO - 'tabpaint']}, 08:01:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:01:52 INFO - 'tests': ['tsvgx', 08:01:52 INFO - 'tsvgr_opacity', 08:01:52 INFO - 'tart', 08:01:52 INFO - 'tscrollx', 08:01:52 INFO - 'cart']}, 08:01:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:01:52 INFO - 'tsvgr_opacity', 08:01:52 INFO - 'tart', 08:01:52 INFO - 'tscrollx', 08:01:52 INFO - 'cart']}, 08:01:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:01:52 INFO - 'talos_options': ['--disable-e10s'], 08:01:52 INFO - 'tests': ['tp5o']}, 08:01:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:01:52 INFO - 'tests': ['tp5o']}, 08:01:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:01:52 INFO - 'talos_options': ['--disable-e10s', 08:01:52 INFO - '--xperf_path', 08:01:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:01:52 INFO - 'tests': ['tp5n']}, 08:01:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:01:52 INFO - 'talos_options': ['--xperf_path', 08:01:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:01:52 INFO - 'tests': ['tp5n']}}, 08:01:52 INFO - 'talos.zip': {'path': '', 08:01:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:01:52 INFO - Running post-action listener: _resource_record_post_action 08:01:52 INFO - ##### 08:01:52 INFO - ##### Finished populate-webroot step (success) 08:01:52 INFO - ##### 08:01:52 INFO - ##### 08:01:52 INFO - ##### Running create-virtualenv step. 08:01:52 INFO - ##### 08:01:52 INFO - Running pre-action listener: _resource_record_pre_action 08:01:52 INFO - Running main action method: create_virtualenv 08:01:52 INFO - Creating virtualenv /builds/slave/test/build/venv 08:01:52 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 08:01:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:01:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:01:53 INFO - Using real prefix '/tools/python27' 08:01:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:01:53 INFO - Installing distribute.............................................................................................................................................................................................done. 08:01:56 INFO - Installing pip.................done. 08:01:56 INFO - Return code: 0 08:01:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:56 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 0x103f22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e5bdacf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc6d30>, '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 0x103beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dbdb20>, '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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:01:56 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 08:01:56 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 08:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKHrZIwcDj/Render', 08:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:56 INFO - 'HOME': '/Users/cltbld', 08:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:56 INFO - 'LOGNAME': 'cltbld', 08:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:56 INFO - 'MOZ_NO_REMOTE': '1', 08:01:56 INFO - 'NO_EM_RESTART': '1', 08:01:56 INFO - 'PAGER': '/bin/cat', 08:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:56 INFO - 'PWD': '/builds/slave/test', 08:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:56 INFO - 'SHELL': '/bin/bash', 08:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SCcPYdvL2/Listeners', 08:01:56 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:56 INFO - 'USER': 'cltbld', 08:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:56 INFO - 'XPC_FLAGS': '0x0', 08:01:56 INFO - 'XPC_SERVICE_NAME': '0', 08:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:56 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 08:01:56 INFO - Cleaning up... 08:01:56 INFO - Return code: 0 08:01:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:56 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 0x103f22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e5bdacf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc6d30>, '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 0x103beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dbdb20>, '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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:01:56 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 08:01:56 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 08:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKHrZIwcDj/Render', 08:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:56 INFO - 'HOME': '/Users/cltbld', 08:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:56 INFO - 'LOGNAME': 'cltbld', 08:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:56 INFO - 'MOZ_NO_REMOTE': '1', 08:01:56 INFO - 'NO_EM_RESTART': '1', 08:01:56 INFO - 'PAGER': '/bin/cat', 08:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:56 INFO - 'PWD': '/builds/slave/test', 08:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:56 INFO - 'SHELL': '/bin/bash', 08:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SCcPYdvL2/Listeners', 08:01:56 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:56 INFO - 'USER': 'cltbld', 08:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:56 INFO - 'XPC_FLAGS': '0x0', 08:01:56 INFO - 'XPC_SERVICE_NAME': '0', 08:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:57 INFO - Downloading/unpacking psutil>=0.7.1 08:01: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 08:01: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 08:01: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 08:01: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 08:01: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 08:01: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 08:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:02:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:00 INFO - Installing collected packages: psutil 08:02:00 INFO - Running setup.py install for psutil 08:02:00 INFO - building 'psutil._psutil_osx' extension 08:02: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 08:02: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:02: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:02:00 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 08:02:01 INFO - building 'psutil._psutil_posix' extension 08:02: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 08:02:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:02:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:02:01 INFO - ^ 08:02:01 INFO - 1 warning generated. 08:02: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 08:02:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:01 INFO - Successfully installed psutil 08:02:01 INFO - Cleaning up... 08:02:01 INFO - Return code: 0 08:02:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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 0x103f22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e5bdacf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc6d30>, '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 0x103beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dbdb20>, '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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:02: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 08:02: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 08:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKHrZIwcDj/Render', 08:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:01 INFO - 'HOME': '/Users/cltbld', 08:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:01 INFO - 'LOGNAME': 'cltbld', 08:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:01 INFO - 'MOZ_NO_REMOTE': '1', 08:02:01 INFO - 'NO_EM_RESTART': '1', 08:02:01 INFO - 'PAGER': '/bin/cat', 08:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:01 INFO - 'PWD': '/builds/slave/test', 08:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:01 INFO - 'SHELL': '/bin/bash', 08:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SCcPYdvL2/Listeners', 08:02:01 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:01 INFO - 'USER': 'cltbld', 08:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:01 INFO - 'XPC_FLAGS': '0x0', 08:02:01 INFO - 'XPC_SERVICE_NAME': '0', 08:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:02: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) 08:02:04 INFO - Installing collected packages: mozsystemmonitor 08:02:04 INFO - Running setup.py install for mozsystemmonitor 08:02:04 INFO - Successfully installed mozsystemmonitor 08:02:04 INFO - Cleaning up... 08:02:04 INFO - Return code: 0 08:02:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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 0x103f22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e5bdacf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc6d30>, '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 0x103beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dbdb20>, '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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:02: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 08:02: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 08:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKHrZIwcDj/Render', 08:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:04 INFO - 'HOME': '/Users/cltbld', 08:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:04 INFO - 'LOGNAME': 'cltbld', 08:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:04 INFO - 'MOZ_NO_REMOTE': '1', 08:02:04 INFO - 'NO_EM_RESTART': '1', 08:02:04 INFO - 'PAGER': '/bin/cat', 08:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:04 INFO - 'PWD': '/builds/slave/test', 08:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:04 INFO - 'SHELL': '/bin/bash', 08:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SCcPYdvL2/Listeners', 08:02:04 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:04 INFO - 'USER': 'cltbld', 08:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:04 INFO - 'XPC_FLAGS': '0x0', 08:02:04 INFO - 'XPC_SERVICE_NAME': '0', 08:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:04 INFO - Downloading/unpacking blobuploader==1.2.4 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:06 INFO - Downloading blobuploader-1.2.4.tar.gz 08:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:02:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:02:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:08 INFO - Downloading docopt-0.6.1.tar.gz 08:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:02:08 INFO - Installing collected packages: blobuploader, requests, docopt 08:02:08 INFO - Running setup.py install for blobuploader 08:02:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:02:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:02:08 INFO - Running setup.py install for requests 08:02:08 INFO - Running setup.py install for docopt 08:02:09 INFO - Successfully installed blobuploader requests docopt 08:02:09 INFO - Cleaning up... 08:02:09 INFO - Return code: 0 08:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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 0x103f22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e5bdacf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc6d30>, '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 0x103beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dbdb20>, '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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:02: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 08:02: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 08:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKHrZIwcDj/Render', 08:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:09 INFO - 'HOME': '/Users/cltbld', 08:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:09 INFO - 'LOGNAME': 'cltbld', 08:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:09 INFO - 'MOZ_NO_REMOTE': '1', 08:02:09 INFO - 'NO_EM_RESTART': '1', 08:02:09 INFO - 'PAGER': '/bin/cat', 08:02:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:09 INFO - 'PWD': '/builds/slave/test', 08:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:09 INFO - 'SHELL': '/bin/bash', 08:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SCcPYdvL2/Listeners', 08:02:09 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:09 INFO - 'USER': 'cltbld', 08:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:09 INFO - 'XPC_FLAGS': '0x0', 08:02:09 INFO - 'XPC_SERVICE_NAME': '0', 08:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-WiHnNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-XqJth7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-WGzHxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-cLdbSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-un9yCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-AxgBe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Cuhjve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-FhDPzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-6ocqGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Pxl_FU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-gvYSNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-m6lByi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-FnKOKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-l9ugdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-tt883k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bD7JRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:11 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-lKisfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:02:11 INFO - Running setup.py install for manifestparser 08:02:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:02:11 INFO - Running setup.py install for mozcrash 08:02:11 INFO - Running setup.py install for mozdebug 08:02:11 INFO - Running setup.py install for mozdevice 08:02:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:02:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:02:11 INFO - Running setup.py install for mozfile 08:02:11 INFO - Running setup.py install for mozhttpd 08:02:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:02:12 INFO - Running setup.py install for mozinfo 08:02:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:02:12 INFO - Running setup.py install for mozInstall 08:02:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:02:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:02:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:02:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:02:12 INFO - Running setup.py install for mozleak 08:02:12 INFO - Running setup.py install for mozlog 08:02:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:02:12 INFO - Running setup.py install for moznetwork 08:02:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:02:12 INFO - Running setup.py install for mozprocess 08:02:12 INFO - Running setup.py install for mozprofile 08:02:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:02:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:02:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:02:13 INFO - Running setup.py install for mozrunner 08:02:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:02:13 INFO - Running setup.py install for mozscreenshot 08:02:13 INFO - Running setup.py install for moztest 08:02:13 INFO - Running setup.py install for mozversion 08:02:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:02:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:02:13 INFO - Cleaning up... 08:02:13 INFO - Return code: 0 08:02:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:13 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')]} 08:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:13 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')]} 08:02:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:13 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 0x103f22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e5bdacf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc6d30>, '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 0x103beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dbdb20>, '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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:02:13 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 08:02:13 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 08:02:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKHrZIwcDj/Render', 08:02:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:13 INFO - 'HOME': '/Users/cltbld', 08:02:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:13 INFO - 'LOGNAME': 'cltbld', 08:02:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:13 INFO - 'MOZ_NO_REMOTE': '1', 08:02:13 INFO - 'NO_EM_RESTART': '1', 08:02:13 INFO - 'PAGER': '/bin/cat', 08:02:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:13 INFO - 'PWD': '/builds/slave/test', 08:02:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:13 INFO - 'SHELL': '/bin/bash', 08:02:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SCcPYdvL2/Listeners', 08:02:13 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:02:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:13 INFO - 'USER': 'cltbld', 08:02:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:13 INFO - 'XPC_FLAGS': '0x0', 08:02:13 INFO - 'XPC_SERVICE_NAME': '0', 08:02:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-auQL3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02: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)) 08:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-iMUzVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02: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)) 08:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-kbeIeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02: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)) 08:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-uFwgM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02: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)) 08:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-WdVhyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02: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)) 08:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-4tMQNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02: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)) 08:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-9bo5sD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:14 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)) 08:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-XeadDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:14 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)) 08:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-TsyrYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:14 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)) 08:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-IrdP0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ruuoqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02: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)) 08:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-inbMjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02: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)) 08:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Eismdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02: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)) 08:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-GxNj_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02: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)) 08:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-aQn7Ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02: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)) 08:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-MBpHZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02: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)) 08:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5zGS89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:15 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)) 08:02:15 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)) 08:02:15 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)) 08:02:15 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)) 08:02:15 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)) 08:02:15 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)) 08:02:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:02:15 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 08:02:15 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 08:02:15 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 08:02:15 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 08:02:15 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 08:02:15 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 08:02:18 INFO - Downloading blessings-1.6.tar.gz 08:02:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:02:18 INFO - Installing collected packages: blessings 08:02:18 INFO - Running setup.py install for blessings 08:02:18 INFO - Successfully installed blessings 08:02:18 INFO - Cleaning up... 08:02:18 INFO - Return code: 0 08:02:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:02:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:18 INFO - Reading from file tmpfile_stdout 08:02:18 INFO - Current package versions: 08:02:18 INFO - blessings == 1.6 08:02:18 INFO - blobuploader == 1.2.4 08:02:18 INFO - docopt == 0.6.1 08:02:18 INFO - manifestparser == 1.1 08:02:18 INFO - mozInstall == 1.12 08:02:18 INFO - mozcrash == 0.17 08:02:18 INFO - mozdebug == 0.1 08:02:18 INFO - mozdevice == 0.48 08:02:18 INFO - mozfile == 1.2 08:02:18 INFO - mozhttpd == 0.7 08:02:18 INFO - mozinfo == 0.9 08:02:18 INFO - mozleak == 0.1 08:02:18 INFO - mozlog == 3.2 08:02:18 INFO - moznetwork == 0.27 08:02:18 INFO - mozprocess == 0.22 08:02:18 INFO - mozprofile == 0.28 08:02:18 INFO - mozrunner == 6.11 08:02:18 INFO - mozscreenshot == 0.1 08:02:18 INFO - mozsystemmonitor == 0.0 08:02:18 INFO - moztest == 0.7 08:02:18 INFO - mozversion == 1.4 08:02:18 INFO - psutil == 3.1.1 08:02:18 INFO - requests == 1.2.3 08:02:18 INFO - wsgiref == 0.1.2 08:02:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:18 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')]} 08:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:18 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')]} 08:02:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:18 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 0x103f22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e5bdacf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc6d30>, '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 0x103beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dbdb20>, '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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:02:18 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 08:02:18 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 08:02:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKHrZIwcDj/Render', 08:02:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:18 INFO - 'HOME': '/Users/cltbld', 08:02:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:18 INFO - 'LOGNAME': 'cltbld', 08:02:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:18 INFO - 'MOZ_NO_REMOTE': '1', 08:02:18 INFO - 'NO_EM_RESTART': '1', 08:02:18 INFO - 'PAGER': '/bin/cat', 08:02:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:18 INFO - 'PWD': '/builds/slave/test', 08:02:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:18 INFO - 'SHELL': '/bin/bash', 08:02:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SCcPYdvL2/Listeners', 08:02:18 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:02:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:18 INFO - 'USER': 'cltbld', 08:02:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:18 INFO - 'XPC_FLAGS': '0x0', 08:02:18 INFO - 'XPC_SERVICE_NAME': '0', 08:02:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:18 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)) 08:02:18 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)) 08:02:18 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)) 08:02:18 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)) 08:02:18 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)) 08:02:18 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)) 08:02:18 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)) 08:02:18 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)) 08:02:18 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)) 08:02:18 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)) 08:02:18 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)) 08:02:18 INFO - Cleaning up... 08:02:18 INFO - Return code: 0 08:02:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:02:18 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')]} 08:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:18 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')]} 08:02:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:18 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 0x103f22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e5bdacf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc6d30>, '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 0x103beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dbdb20>, '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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:02:18 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 08:02:18 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 08:02:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKHrZIwcDj/Render', 08:02:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:18 INFO - 'HOME': '/Users/cltbld', 08:02:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:18 INFO - 'LOGNAME': 'cltbld', 08:02:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:18 INFO - 'MOZ_NO_REMOTE': '1', 08:02:18 INFO - 'NO_EM_RESTART': '1', 08:02:18 INFO - 'PAGER': '/bin/cat', 08:02:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:18 INFO - 'PWD': '/builds/slave/test', 08:02:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:18 INFO - 'SHELL': '/bin/bash', 08:02:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SCcPYdvL2/Listeners', 08:02:18 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:02:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:18 INFO - 'USER': 'cltbld', 08:02:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:18 INFO - 'XPC_FLAGS': '0x0', 08:02:18 INFO - 'XPC_SERVICE_NAME': '0', 08:02:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:19 INFO - Downloading/unpacking jsonschema 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:02:21 INFO - Downloading/unpacking functools32 (from jsonschema) 08:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:21 INFO - Downloading functools32-3.2.3-2.tar.gz 08:02:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:02:22 INFO - warning: no files found matching '*.txt' 08:02:22 INFO - no previously-included directories found matching 'build' 08:02:22 INFO - no previously-included directories found matching 'dist' 08:02:22 INFO - no previously-included directories found matching '.git*' 08:02:22 INFO - Installing collected packages: jsonschema, functools32 08:02:22 INFO - Running setup.py install for functools32 08:02:22 INFO - warning: no files found matching '*.txt' 08:02:22 INFO - no previously-included directories found matching 'build' 08:02:22 INFO - no previously-included directories found matching 'dist' 08:02:22 INFO - no previously-included directories found matching '.git*' 08:02:22 INFO - Successfully installed jsonschema functools32 08:02:22 INFO - Cleaning up... 08:02:22 INFO - Return code: 0 08:02:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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 0x103f22a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e5bdacf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc6d30>, '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 0x103beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dbdb20>, '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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 08:02: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 08:02: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 08:02:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKHrZIwcDj/Render', 08:02:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:22 INFO - 'HOME': '/Users/cltbld', 08:02:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:22 INFO - 'LOGNAME': 'cltbld', 08:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:22 INFO - 'MOZ_NO_REMOTE': '1', 08:02:22 INFO - 'NO_EM_RESTART': '1', 08:02:22 INFO - 'PAGER': '/bin/cat', 08:02:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:22 INFO - 'PWD': '/builds/slave/test', 08:02:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:22 INFO - 'SHELL': '/bin/bash', 08:02:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SCcPYdvL2/Listeners', 08:02:22 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:02:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:22 INFO - 'USER': 'cltbld', 08:02:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:22 INFO - 'XPC_FLAGS': '0x0', 08:02:22 INFO - 'XPC_SERVICE_NAME': '0', 08:02:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:22 INFO - Downloading/unpacking flake8 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:02:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:02:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:02:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:02:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:02:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:02:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:02:26 INFO - no previously-included directories found matching 'docs/_build' 08:02:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:02:26 INFO - Running setup.py install for pep8 08:02:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:02:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:02:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:02:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:02:26 INFO - no previously-included directories found matching 'docs/_build' 08:02:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:02:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:02:26 INFO - Cleaning up... 08:02:26 INFO - Return code: 0 08:02:26 INFO - Running post-action listener: _resource_record_post_action 08:02:26 INFO - Running post-action listener: _start_resource_monitoring 08:02:26 INFO - Starting resource monitoring. 08:02:26 INFO - ##### 08:02:26 INFO - ##### Finished create-virtualenv step (success) 08:02:26 INFO - ##### 08:02:26 INFO - ##### 08:02:26 INFO - ##### Running install step. 08:02:26 INFO - ##### 08:02:26 INFO - Running pre-action listener: _resource_record_pre_action 08:02:26 INFO - Running main action method: install 08:02:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:27 INFO - Reading from file tmpfile_stdout 08:02:27 INFO - Detecting whether we're running mozinstall >=1.0... 08:02:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:02:27 INFO - Reading from file tmpfile_stdout 08:02:27 INFO - Output received: 08:02:27 INFO - Usage: mozinstall [options] installer 08:02:27 INFO - Options: 08:02:27 INFO - -h, --help show this help message and exit 08:02:27 INFO - -d DEST, --destination=DEST 08:02:27 INFO - Directory to install application into. [default: 08:02:27 INFO - "/builds/slave/test"] 08:02:27 INFO - --app=APP Application being installed. [default: firefox] 08:02:27 INFO - mkdir: /builds/slave/test/build/application 08:02: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'] 08:02: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 08:03:00 INFO - Reading from file tmpfile_stdout 08:03:00 INFO - Output received: 08:03:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:03:00 INFO - Running post-action listener: _resource_record_post_action 08:03:00 INFO - ##### 08:03:00 INFO - ##### Finished install step (success) 08:03:00 INFO - ##### 08:03:00 INFO - ##### 08:03:00 INFO - ##### Running run-tests step. 08:03:00 INFO - ##### 08:03:00 INFO - Running pre-action listener: _resource_record_pre_action 08:03:00 INFO - Running main action method: run_tests 08:03:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:03:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:03:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:03:00 INFO - 2016-05-12 08:03:00.145 screenresolution[1658:7736] starting screenresolution argv=screenresolution get 08:03:00 INFO - 2016-05-12 08:03:00.148 screenresolution[1658:7736] Display 0: 1600x1200x32@60 08:03:00 INFO - 2016-05-12 08:03:00.154 screenresolution[1659:7738] starting screenresolution argv=screenresolution list 08:03:00 INFO - Available Modes on Display 0 08:03:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:03:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:03:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:03:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:03:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:03:00 INFO - Intel Iris: 08:03:00 INFO - Chipset Model: Intel Iris 08:03:00 INFO - Type: GPU 08:03:00 INFO - Bus: Built-In 08:03:00 INFO - VRAM (Dynamic, Max): 1536 MB 08:03:00 INFO - Vendor: Intel (0x8086) 08:03:00 INFO - Device ID: 0x0a2e 08:03:00 INFO - Revision ID: 0x0009 08:03:00 INFO - Displays: 08:03:00 INFO - Display: 08:03:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:03:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:03:00 INFO - Display Serial Number: 1600x1200 60 08:03:00 INFO - Main Display: Yes 08:03:00 INFO - Mirror: Off 08:03:00 INFO - Online: Yes 08:03:00 INFO - Rotation: Supported 08:03:00 INFO - Return code: 0 08:03:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:03:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:03:00 INFO - Python 2.7.3 08:03:00 INFO - Return code: 0 08:03:00 INFO - grabbing minidump binary from tooltool 08:03:00 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')]} 08:03:00 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103dc6d30>, '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 0x103beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dbdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:03:00 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 08:03:00 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 08:03:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:03:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:03:00 INFO - Return code: 0 08:03:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:03:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:03:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:03:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:03:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:03:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:03:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:03:01 INFO - Return code: 0 08:03:01 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/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 08:03:01 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/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0181 --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 08:03:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xKHrZIwcDj/Render', 08:03:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:01 INFO - 'HOME': '/Users/cltbld', 08:03:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:01 INFO - 'LOGNAME': 'cltbld', 08:03:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:03:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:03:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:01 INFO - 'MOZ_NO_REMOTE': '1', 08:03:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:03:01 INFO - 'NO_EM_RESTART': '1', 08:03:01 INFO - 'PAGER': '/bin/cat', 08:03:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:01 INFO - 'PWD': '/builds/slave/test', 08:03:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:03:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:01 INFO - 'SHELL': '/bin/bash', 08:03:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9SCcPYdvL2/Listeners', 08:03:01 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 08:03:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:01 INFO - 'USER': 'cltbld', 08:03:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:01 INFO - 'XPC_FLAGS': '0x0', 08:03:01 INFO - 'XPC_SERVICE_NAME': '0', 08:03:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:01 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/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 08:03:01 INFO - mozversion application_buildid: 20160512060851 08:03:01 INFO - mozversion application_changeset: 1e5b69b0387beb39a0b997938c07a6a05d4ae42a 08:03:01 INFO - mozversion application_display_name: Nightly 08:03:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:03:01 INFO - mozversion application_name: Firefox 08:03:01 INFO - mozversion application_remotingname: firefox 08:03:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:03:01 INFO - mozversion application_vendor: Mozilla 08:03:01 INFO - mozversion application_version: 49.0a1 08:03:01 INFO - mozversion platform_buildid: 20160512060851 08:03:01 INFO - mozversion platform_changeset: 1e5b69b0387beb39a0b997938c07a6a05d4ae42a 08:03:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:03:01 INFO - mozversion platform_version: 49.0a1 08:03:01 INFO - using testdate: 1463065381 08:03:01 INFO - actual date: 1463065381 08:03:01 INFO - starting webserver on 'localhost:49277' 08:03:01 INFO - SUITE-START | Running 2 tests 08:03:01 INFO - TEST-START | dromaeo_css 08:03:01 INFO - operating with platform_type : mac_ 08:03:01 INFO - Initialising browser for dromaeo_css test... 08:03:01 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmp2LCNiT/profile http://localhost:49277/getInfo.html) 08:03:04 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 08:03:04 INFO - PROCESS | 1677 | colorDepth:24 08:03:04 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 08:03:04 INFO - PROCESS | 1677 | __metrics 08:03:04 INFO - PROCESS | 1677 | 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" 08:03:04 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:03:05 INFO - TEST-INFO | 1677: exit 0 08:03:05 INFO - Browser initialized. 08:03:05 INFO - Running cycle 1/1 for dromaeo_css test... 08:03:05 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmp2LCNiT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:17 INFO - PROCESS | 1679 | RSS: Main: 238759936 08:03:17 INFO - PROCESS | 1679 | 08:04:08 INFO - PROCESS | 1679 | 1463065448365 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] 08:06:18 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 08:06:19 INFO - PROCESS | 1679 | RSS: Main: 361426944 08:06:19 INFO - PROCESS | 1679 | 08:09:23 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 08:09:23 INFO - PROCESS | 1679 | RSS: Main: 376872960 08:09:23 INFO - PROCESS | 1679 | 08:12:25 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 08:12:26 INFO - PROCESS | 1679 | RSS: Main: 370126848 08:12:26 INFO - PROCESS | 1679 | 08:15:28 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 08:15:29 INFO - PROCESS | 1679 | RSS: Main: 368943104 08:15:29 INFO - PROCESS | 1679 | 08:18:32 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 08:18:33 INFO - PROCESS | 1679 | RSS: Main: 332857344 08:18:33 INFO - PROCESS | 1679 | 08:21:36 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 08:21:36 INFO - PROCESS | 1679 | RSS: Main: 380178432 08:21:36 INFO - PROCESS | 1679 | 08:21:36 INFO - PROCESS | 1679 | __start_tp_report 08:21:36 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 08:21:36 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 08:21:36 INFO - PROCESS | 1679 | |i|pagename|runs| 08:21:36 INFO - PROCESS | 1679 | |0;dojo.html;2830;2954.759001219488;3432.124808173916;3467.4625433057886;3502.684758371746;5654.943450565494;5674.773009079642;5785.797497087603;5793.942060579393;5846.298444186693;5673.744681489333;5736.885262294752;5798.449147331007;5830.0380437227805;5879.735411906464;5139.820106296281;5184.2223666450145;5244.947550524484;5261.973690131563;5330.3603567571945;5302.655327403726;5343.278657381262;5346.010987967219;5354.625176237665;5373.355197376301;5141.254518094893;5154.484551544853;5207.791688332462;5241.056609810233;5241.528262456378;5177.326947496839;5192.350956130484;5195.818146364897;5266.394364648077;5301.363836339626;5181.8336179945745;5196.714180720059;5243.239730239094;5270.736463176826;5273.209018647195;5519.282493275889;5567.916481252784;5611.270534830487;5731.598788084836;5831.125331200312;5221.2951251580935;6167.229096362955;6205.22434695663;6226.097215903668;6230.7819226326865;6136.969315153396;6182.1345011698395;6183.814485565395;6211.09939058834;6213.937860621426;5703.743331550048;5776.1624564437925;5794.1308803679785;5850.736716847752;5882.411758824126;6223.96888015557;6287.49700023997;6312;6327.209098862642;6329.68351582419;6193.349698281706;6194.5973511721595;6198.6280823150755;6213.565050446426;6218.689065546704;6067.544934129958;6175.660338681326;6188.4739797116845;6207.844803879939;6216;6188.907166392508;6203.751849925952;6264.1856558648;6283.560150789401;6285.214348206474;5952.285725712855;6200.3179650238435;6232;6259.874802504016;6342.587731797418;4820.469748327681;4860.246661767432;4885.145099607555;4933.383327084114;4964.751762411865;4388.2759344707765;4392.780360981938;4412.117576484652;4435.423394958635;4477.1493416250805;5825.533957283407;5847.26909136358;5854.385289544622;5858.5898987070495;5874.236349274653;6154.846128846814;6195.380461953859;6222.657753823583;6273.12176295319;6285.025820997753;5814.011618024946;5836.970815145982;5839.649621022752;5868.061110222344;5874.911876321773;5396.541293989976;5422.078246698071;5430.70131142791;5433.646812957145;5435.103207970719;6228.906566401508;6262.405071518289;6315.747370105144;6328.493720502441;6388.105665206875;6290.496760259077;6297.244330680348;6298.622082675054;6299.464545513682;6329.240491141092;6377;6399.168108145911;6404.71178796946;6414.5189110815945;6415.871682566416;6094.54290928191;6104.17593624855;6135.508280839814;6212.720427580679;6215.502759779117;6250.812475625738;6256.405641464054;6291.591046581958;6298.307186209605;6307.211598550181;6140.938590614037;6186.659733714689;6187.907181392193;6190.81427557174;6203.100550420254;5986.431289027535;5992.5205983520345;6001.489873360813;6053.182820319201;6164.291106522807;5034.320366750589;6028.87942241144;6045.6070355426755;6063.72713227897;6067.211262535842;6157.415045570664;6172.567920245539;6175.166352542529;6183.907241391473;6205.751769929152;5791.7683292667825;5888.823335299948;5946.07835785454;5968.641881487125;6007.219061522149 08:21:36 INFO - PROCESS | 1679 | |1;ext.html;496.73921191374524;515.5488947171225;518.541091134346;531.7314756048193;553.6318348298382;27058.729412705867;42309.94225144369;43534.34698479525;43569.56430435694;46234.76882615582;44393;44766.10467790651;44816.879578010565;44859;44922.77538612302;8598.613062412176;9698;9881.505924703772;9912.107910288072;9919.256055795808;8691.695790647329;11597.652070437858;11813.291202527833;11853;11973.820392694117;11836.349000804952;11882.643520694393;11889.524419023228;11918.523259069627;11922.642320730392;8702.216800487924;11714.062874969983;11728.237664551776;11884.821727674049;11971.341576213305;11742;11801.822972655416;11813.054955603531;11815.172937894351;11845;39335.21329573393;39737.00657483586;39743.205135897406;39757.60242397568;39832.20335593272;3173.873045078194;3213.5822343095483;3267.248532837437;3268.967310326914;3395.47370157626;23031;28891.855540722372;29254;29616.25959351012;29986.800527979074;2776.41695243999;2826.4771017361854;2854.215090850012;2856.6143570618115;2859.25659328574;377715;379980.4003919906;380081.099594503;380807;380903;58677;60546;60675;60716;60732;30172;30400.54399184014;37431.62568374336;37552.87341379763;39071;16229.837701622833;16321.34714611426;16343.101129437757;16368.918155409146;16378.836211637969;11390.829137562943;11582;11600.6519804406;11798.351090690094;12325.322107284186;14086.366113524917;19496.363844518695;20385.286514971904;20566.58866822655;20567.58864822725;7874;7983.600819958979;7984.041914970241;7997.966365276763;8031.477953932976;955.8757361542955;1056.963006294776;1119.2724728926264;1150.9021733152777;1187.6555798818247;24950.875245623654;29528.409431811673;29674.703252967192;30647.540286895714;30862.537061944087;1056.1814593689799;1085.5440714899762;1135.8125909224884;1151.5739176504746;1162.3897453836803;13604.843588294907;14052.297385130703;15406.537803865678;15715.21423928799;15834.445794397141;663534;667014;667363;670099;671087;56531.86936261334;60338.69830650819;60419.69790151021;60424.39575604276;60645.69677151586;1297.3967105296083;1335.5192130833086;1336.8128462015131;1442.343733601209;1463.5024091808837;29613.03845829803;31428.685713142575;31721.8413907929;31763;32128.518072229403;55100;55497;56002;56124.1937903095;56124.719376402856;485752;539009;546877;547167;549341;22115.557688846457;23632;24285.51428971446;26891.327716807235;27307.590386143827;42922.35616465693;45485.09029819451;46477.767611161726;47225.527744722116;47774;37001;37071.81464092662;37174.628253717114;37723.81138094292;37819.62180378161;36771.20648193373;37238;37740.81129594334;37932.43101353537;38133;36727.632723672425;37040.81479592585;37041.62958370382;37208.140092755086;38194;32104.83947580247;32842.343153137284;33781.49327760036;34079.318413632085;34962.47556286708;24094;25196.49607007886;25238;25701;25841.612375814002 08:21:36 INFO - PROCESS | 1679 | |2;jquery.html;390.1923019349946;394.08375526899965;394.57780175212525;399.5046142782948;417.8391319342052;15040.097594144332;17051.914740426295;19153.425397238105;19853.404397868067;20884.269050583232;21034.68447973278;21097.789022109773;21124.155033798666;21277.787222127776;21625.243116490925;5749.195112684217;5976.193213916109;6053.576249662503;6056.242969628796;6060.757569697207;5804.312601271345;5958.5828991970575;5969.164316995624;6036.879262414749;6046.3349031606485;5953.940460595382;5968.850778730544;5979.312379076397;5983.910241346383;5993.951058564734;5766.826995190151;5871.530277577805;5940.168376427304;5953.255843019622;5979;4430.446194225722;5130.358705161855;5150.459201783834;5156.845294641167;5198.792048318062;24544;25343.746562534325;25618.87190564054;25626.48747025049;25805.61291580628;2069.6791997240452;2073.8237249833783;2261.8642881427004;2290.9541809163725;2331.825113116506;14923.552293431214;15018.474353397578;15194.848051519453;15335.30991105403;15664;1462.3273294284643;1500.8574185452474;1811.5544015578864;1813.9002354870477;1835.9082045897785;121067.39466302701;125235.99999999908;126747;127218.36390818079;127401;10892.727681808019;11171.888281117248;11431.542738290542;11595.884041159481;11628;12665.36673166338;13344.199348039148;13372.197668139943;14318.85681143196;14333.856661433252;6833.555818871758;7954.562499062491;8021.719239826695;8416;8760.08019157992;8276.586170691442;8369.246767790928;8389.783481395163;8525;8711.47731136134;5597.44025597437;6710.188249172794;6888.724451022003;6893.724251029903;6914.723411063602;1910.7516022916932;1935.6709359408935;1935.9903200484189;1964.2634012245408;1992.85053620979;775.9922400776032;917.4357769971552;964.81668482988;971.2715463402449;986.363795351994;21021.05405256767;21729.022194001307;21793.782062179493;21941.341759746912;21942.78057219439;950.6054987180381;991.3556188477547;998.1116806042628;1000.7948370584013;1042.4318746283293;10693.19801014927;11436.714082148013;11560.075193984461;11655.184137110491;11713.824292635618;607451;608262;609305;611090;632602;119297.40351298361;119599.80400196061;126073.96991138947;152004;155117.8149857252;902.2601466797295;1018.3584341864578;1018.5772904244656;1058.5501162006117;1069.2087854987253;16649.001059936443;18253.269869205084;18658.253669853053;18826.623467530848;19009.524761881064;47431;55794.442055578926;56250;56913.71543142258;58300.70849645725;83793;83981.16018839978;84668;85623;86712.5664371674;7078.610676412641;7524.021877155935;7628;7685.538867667958;7787.805304867424;12691.555795547112;12859.26399936034;13010.739785204432;13258.86741132615;13262.27057511846;8975.596098175729;10497.265191436527;11276.9436152822;11279.041281491165;11404.087672985976;10853.728656783644;11174.608888689181;11329.603463878724;11489.195756296978;11631.941840290745;10838.620648277272;11067.059299959594;11399.544018239178;11744.412779361168;11786.705332366759;8219.917800821915;8259;8331.583420828812;8383.245507904317;8680.175383338572;10823.296485728402;11118.666440006813;11129.610463633733;11343.262687925588;11477.139214558774 08:21:36 INFO - PROCESS | 1679 | |3;mootools.html;1395.3720825628466;1440.092741572809;1600.991995040025;1810.7827060752704;1982.6629472989591;7875.803104922373;8480.151984801525;8787.692430764933;8792.2966162707;8820.647174113028;8341.91658083419;8658.393912426118;8668.653253869854;8742.169493898069;8750.606222719976;1662.3184494357315;2134.6050980568566;2265.844419346137;2380.1907351500486;2444.2545023767743;2215.700880381144;2227.0757635581213;2377.5720370333333;2465.3097132802845;2468.5556599812026;2433.549793288247;2438.670779444779;2441.7436169202247;2443.3769388805918;2446.2905757330354;2374.9050037998545;2418.8790560471907;2423.7818596326415;2426.793722533578;2428.550718117154;2057.2182570623067;2121.1727426303755;2337.906483740648;2426.8786560672074;2441.523902838947;8565;8680.739577812676;8682.43564168327;8686.131386861327;8692;5221.843344699665;5341.1187154119525;5346.893062138774;5348.572114230852;5384.246205531229;3414.709749671281;3438.793672379665;3445.586529616437;3528.311979164064;3537.506058228968;963.393062370709;969.5830792759111;985;1018.5823812237021;1022.4223313827664;11669.824952625717;11900.940495297555;11907.940460297643;11912.404379780975;11916.880831191664;4798.664093513421;4882.194437917704;4973.55238028572;4976.154053810861;4996.7501624918605;10239.436830974219;10247.743806404751;10260.640877569249;10263.7434064149;10264.846027309597;5428.75570599324;5448.318960129984;5479.205515200353;5480.04099282624;5481.205225242317;4241.491021077491;4432.2908334666945;4823.541763532458;4924.236743304794;4990.850274491771;3242.854071566785;3818.140918293417;4049.716519843583;4138.627523522898;4163.97918010408;1338.4713038349992;1355.627202519315;1366.2485632901864;1367.6870204603492;1374.093098554949;911.40758506971;922.6217250927222;930.316217580078;940.05994005994;952.3048174832411;4840.515948405202;5587.69267690281;5744;5844.035734103911;5949.524038076944;947.2090804178588;964.840801267797;975.2441857560306;977.237754551451;996.7009897031006;3844.3272427325105;3892.163184915257;3935.976646072042;3968.6031396860085;4005.6595189409227;10336.638217662496;10700.89299106999;10749.40878251688;10856.511456984455;10867.891321086689;10720.946395268129;10810.513526891309;10852.837207441737;10861.1854110942;10866.348019118877;8196.959015204886;8282.917170828336;8293.626786794608;8304.460210086325;8312.127226641236;5643.35101463337;5715.771369145187;5850.268716410449;5866.208061911588;5979.192808970907;9722.368046077265;9908.959559246463;10196.286259961731;10197.694069177936;10222;9523.761910951533;10214.897851021691;10279.537420815932;10299.867014628235;10316.587336506456;6770.729170833111;6943.583384996917;7135.393491553276;7673.616319183907;7678.270564296383;6426.839329016812;7807.141214466291;8410.579471026547;8414.116517765546;8507.787305317417;7388.1503627083575;7469.813254668677;7487.5507469552;7634.961825190839;7653.846923061396;7084;7339.2660733928315;7355.5586664800285;7367.7421290256725;7489.28851759082;6979.755708550177;7068.893966590608;7078.4691148163065;7079.115110611174;7080.964595176991;4813.975930120327;4879.731614761222;4934.580560652241;4993.051320249141;5136.460671629575;4884.218525035977;4921.261810728276;4993.500649935123;5058.974705126451;5200.817971370984 08:21:36 INFO - PROCESS | 1679 | |4;prototype.html;312.5639732573059;314.35870823520014;328.0928233434554;331.3836264547942;333.5946824608102;4446.266366049598;4946.3322451469085;5216.60875434343;5393.73031348433;5400.378956420013;4199.916001679972;4906.975465122669;4922.80308787648;5215.748220427098;5275.49882761138;3340.5824271966003;3637.9090522736947;4064.166845796605;4094.078832262747;4113.161918276394;3422.897313080599;3609.1338078861017;3903.7072219583647;3985.980070099645;4147.917041659165;3415.3189746049356;3802.391617341226;4070.5318888327924;4103.917921641565;4129.298019336705;3900.161465284963;4042.959570404317;4046.1300820323477;4053.7365071270274;4091.243120022805;3521.964780352215;3555.3600351936643;3564.697000754939;3576.1059735066233;3579.624139465345;4127.834886604533;4188.832446702128;4332.805023773938;5003.924941125886;5231.450697676727;3679.9816000919823;4348.826046958118;4563.520830312803;4635.490096089427;4669.346291519156;4757.738324392157;5264.394594621629;5616.63491873031;5627.577931655102;5633.6619802811965;3284.753643476749;3857.57269810172;3900.2979463696524;3907.2966865964113;3908.667763240128;4983.426905905831;5626.774929002835;5788.044972579477;5903.439173278532;5903.586748927577;3656.4880916671686;3748.83130259139;3919.3141200289833;3919.451276821247;3939.113699417608;3788.318102741533;3930.331843586597;3974.8608798691907;3981.969195710151;4013.7190396671954;4033.515978082649;4414.668899832525;4423.778811059434;4641.721496710208;4659.58063774262;5616.410276920946;5625.409332020161;5660.377358490563;5673.659580425187;5676.4323567642905;1212.2484059882752;1336.3051213369188;1355.1868878672915;1360.823092997904;1362.1078193783087;803.4135081390617;811.679386642273;839.7068514487621;840.5923127283263;873.6330741088758;4359.346098085249;5209.7916083356995;5235.842924712265;5273.681579605099;5283.894322113536;4869.342638743795;5168.888688931897;5739.85650358736;5768.826935191951;5777;4509.661775366795;4961.5286547777905;5158.277841102249;5259.737013149327;5282.385219316463;2565.3073670108965;2903.637045369329;3013.2316259353724;3013.2918764090423;3037.39252149571;5307.734613269321;5550.393173665848;5857.4435428634215;6017.217761690952;6056.424639659225;5343.973280133574;5511.559075274049;5709.800941802185;6026.849328766816;6056.606320589148;6047.971844786397;6050.850338435683;6090.969545152246;6095.9273846892265;6121.4490695837585;4816.735079570657;5438.972805135949;5838.299404071538;5868.618539794899;5871.207387002871;5007.524285192901;5390.867917737319;5517.310336207974;5776.249087618583;5818.9127163093435;5599.22838195096;5827.9417205829095;5949.494292985145;5955.821325360246;6150;3646.762960407565;3908.2769687607883;3957.4459575658857;3972.7020473464027;3990.38149086892;4803.735794531194;5014.899702006012;5061.822836200862;5378.085725426608;5936.495397891227;4722.480527141943;5602.831915042555;5623.718814059199;5651.4348565143155;5656.264685591012;4518.43519560055;5220.295260139833;5602.431319230622;5641.605087643826;5691.4877661010705;5210.791568337376;5500.257465242141;5552.944470555404;5600.915986260291;5619.466150715675;4611.354410382616;5663.57523185771;5680.6591604503865;5701.486866182064;5702.942970570241;5251.89496210066;5402.297701298806;5506.421825708403;5577.749001295033;5639.96961094014 08:21:36 INFO - PROCESS | 1679 | |5;yui.html;735.3896266099136;736.675862620447;745.6495447139843;752.2778132992327;792.5442870349549;14991.250437478124;15384.461543845968;15619.531414057568;15620.609484762872;15631.765523517157;14179.78730319046;14236.857631423683;14336.569902902904;14656;14689.559313220621;724.8731471992406;916.2898753466051;924.8381533231691;935.6210734652477;935.976600584984;744.7505085796265;748.8726916424221;749.4978364495773;752.3229093815582;900.7387857521311;970.9854352184723;973.3478569358547;974.098958463422;975.4244995452682;976.5215044628152;966.7873067925045;967.7822489939778;967.8451447768358;967.88869280033;970.6699722094522;565.7934853778382;700.4116542104606;720.103471178381;721.6319676964786;722.9457790665668;14985.775213371808;15025.398984040625;15032.549023529311;15035.54893353201;15055.924720376437;12206.145569810116;12289.324087175202;12315.261084334968;12548.498060077585;12589;10307.33002354852;10353.534090965924;10421.166306695446;10688.679339619746;10921.726956826064;3477.565304336958;3983.7410568313257;4052.837886484537;4088.6524645405175;4130.153318569686;60451;74753;78325.55907188865;78342.43315133665;78522.42955140866;9457.38526995743;9457.90542094584;9690.273229507815;9704.902950970541;9762.267829912786;21406.57186856254;21471.248506302203;21471.67792483114;21475.248366307103;21495;7237.63811809418;7393.223711510322;7396.408287337;7397;7514.135874374517;3934.370500719871;3952.288588054178;4069.9796501017304;4072.572379900127;4106.876793696194;3358.781679190845;3470.8091054991714;3480.147363895811;3483.7038851697384;3508.8771892983623;602.1239097113689;607.9878402431926;608.716946619824;609.20194545146;612.4916319454842;478.21333905725584;489.300300570188;495.7100096443549;521.0048806779034;565.1522715926111;7324.121105467378;7724;7745.8450830983065;7767.572783496962;7831.334336581454;462.6213882629782;479.4031430868591;537.7677676684518;562.3734535967699;569.4078158714973;3067.8466076696523;3524.54180956474;3633;3669.2478042001326;3850.8074596270076;79272.60363698144;79650;84911;86911.1308886903;90022.98713317579;65492;67394.98907516392;68691.3130868685;72480;73283;66606.33484162934;66712.66229708267;66900;68020.6598967002;68729;8073.798155046171;8085.110637829952;8281.047679516932;8294;8324.209200125979;37281;38986.415203772536;39554.802225988686;40136.197276054896;42806.71579852609;58574;58707.11939320828;60102.49743756441;60447;61471.46321342002;11143.83284250753;11191.72020699489;11279.266847655204;11288.363187337625;11509.942450287695;11865.644030679094;11930.284182949052;12050.638480845588;12064;13036.543720969721;11489.310641361544;11687;12110.333931633493;12410.937945310216;12412.503499859904;11093.54322278777;11116.037843950984;11161.06352428572;11214.439278036229;12329.93835030855;11270.887291126985;11305.321680699184;11306.208565400342;11689.181757277249;11789.292642441482;9374.203192728695;9384.67153649619;9396.436213827192;9669.70990870275;10078.873379612409;7450;9457.290703197425;9571;9746.366486178376;9847.60609575609 08:21:36 INFO - PROCESS | 1679 | __end_tp_report 08:21:36 INFO - PROCESS | 1679 | __start_cc_report 08:21:36 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1223 08:21:36 INFO - PROCESS | 1679 | __end_cc_report 08:21:36 INFO - PROCESS | 1679 | __startTimestamp1463066496856__endTimestamp 08:21:36 INFO - PROCESS | 1679 | 08:21:36 INFO - PROCESS | 1679 | ------- Summary: start ------- 08:21:36 INFO - PROCESS | 1679 | Number of tests: 6 08:21:36 INFO - PROCESS | 1679 | 08:21:36 INFO - PROCESS | 1679 | [#0] dojo.html Cycles:165 Average:5758.88 Median:5986.43 stddev:660.44 (11.0%) stddev-sans-first:621.21 08:21:36 INFO - PROCESS | 1679 | Values: 2830.0 2954.8 3432.1 3467.5 3502.7 5654.9 5674.8 5785.8 5793.9 5846.3 5673.7 5736.9 5798.4 5830.0 5879.7 5139.8 5184.2 5244.9 5262.0 5330.4 5302.7 5343.3 5346.0 5354.6 5373.4 5141.3 5154.5 5207.8 5241.1 5241.5 5177.3 5192.4 5195.8 5266.4 5301.4 5181.8 5196.7 5243.2 5270.7 5273.2 5519.3 5567.9 5611.3 5731.6 5831.1 5221.3 6167.2 6205.2 6226.1 6230.8 6137.0 6182.1 6183.8 6211.1 6213.9 5703.7 5776.2 5794.1 5850.7 5882.4 6224.0 6287.5 6312.0 6327.2 6329.7 6193.3 6194.6 6198.6 6213.6 6218.7 6067.5 6175.7 6188.5 6207.8 6216.0 6188.9 6203.8 6264.2 6283.6 6285.2 5952.3 6200.3 6232.0 6259.9 6342.6 4820.5 4860.2 4885.1 4933.4 4964.8 4388.3 4392.8 4412.1 4435.4 4477.1 5825.5 5847.3 5854.4 5858.6 5874.2 6154.8 6195.4 6222.7 6273.1 6285.0 5814.0 5837.0 5839.6 5868.1 5874.9 5396.5 5422.1 5430.7 5433.6 5435.1 6228.9 6262.4 6315.7 6328.5 6388.1 6290.5 6297.2 6298.6 6299.5 6329.2 6377.0 6399.2 6404.7 6414.5 6415.9 6094.5 6104.2 6135.5 6212.7 6215.5 6250.8 6256.4 6291.6 6298.3 6307.2 6140.9 6186.7 6187.9 6190.8 6203.1 5986.4 5992.5 6001.5 6053.2 6164.3 5034.3 6028.9 6045.6 6063.7 6067.2 6157.4 6172.6 6175.2 6183.9 6205.8 5791.8 5888.8 5946.1 5968.6 6007.2 08:21:36 INFO - PROCESS | 1679 | 08:21:36 INFO - PROCESS | 1679 | [#1] ext.html Cycles:180 Average:66200.20 Median:33311.92 stddev:144521.88 (433.8%) stddev-sans-first:144843.10 08:21:36 INFO - PROCESS | 1679 | Values: 496.7 515.5 518.5 531.7 553.6 27058.7 42309.9 43534.3 43569.6 46234.8 44393.0 44766.1 44816.9 44859.0 44922.8 8598.6 9698.0 9881.5 9912.1 9919.3 8691.7 11597.7 11813.3 11853.0 11973.8 11836.3 11882.6 11889.5 11918.5 11922.6 8702.2 11714.1 11728.2 11884.8 11971.3 11742.0 11801.8 11813.1 11815.2 11845.0 39335.2 39737.0 39743.2 39757.6 39832.2 3173.9 3213.6 3267.2 3269.0 3395.5 23031.0 28891.9 29254.0 29616.3 29986.8 2776.4 2826.5 2854.2 2856.6 2859.3 377715.0 379980.4 380081.1 380807.0 380903.0 58677.0 60546.0 60675.0 60716.0 60732.0 30172.0 30400.5 37431.6 37552.9 39071.0 16229.8 16321.3 16343.1 16368.9 16378.8 11390.8 11582.0 11600.7 11798.4 12325.3 14086.4 19496.4 20385.3 20566.6 20567.6 7874.0 7983.6 7984.0 7998.0 8031.5 955.9 1057.0 1119.3 1150.9 1187.7 24950.9 29528.4 29674.7 30647.5 30862.5 1056.2 1085.5 1135.8 1151.6 1162.4 13604.8 14052.3 15406.5 15715.2 15834.4 663534.0 667014.0 667363.0 670099.0 671087.0 56531.9 60338.7 60419.7 60424.4 60645.7 1297.4 1335.5 1336.8 1442.3 1463.5 29613.0 31428.7 31721.8 31763.0 32128.5 55100.0 55497.0 56002.0 56124.2 56124.7 485752.0 539009.0 546877.0 547167.0 549341.0 22115.6 23632.0 24285.5 26891.3 27307.6 42922.4 45485.1 46477.8 47225.5 47774.0 37001.0 37071.8 37174.6 37723.8 37819.6 36771.2 37238.0 37740.8 37932.4 38133.0 36727.6 37040.8 37041.6 37208.1 38194.0 32104.8 32842.3 33781.5 34079.3 34962.5 24094.0 25196.5 25238.0 25701.0 25841.6 08:21:36 INFO - PROCESS | 1679 | 08:21:36 INFO - PROCESS | 1679 | [#2] jquery.html Cycles:180 Average:36241.02 Median:21942.06 stddev:102741.41 (468.2%) stddev-sans-first:102994.36 08:21:36 INFO - PROCESS | 1679 | Values: 390.2 394.1 394.6 399.5 417.8 15040.1 17051.9 19153.4 19853.4 20884.3 21034.7 21097.8 21124.2 21277.8 21625.2 5749.2 5976.2 6053.6 6056.2 6060.8 5804.3 5958.6 5969.2 6036.9 6046.3 5953.9 5968.9 5979.3 5983.9 5994.0 5766.8 5871.5 5940.2 5953.3 5979.0 4430.4 5130.4 5150.5 5156.8 5198.8 24544.0 25343.7 25618.9 25626.5 25805.6 2069.7 2073.8 2261.9 2291.0 2331.8 14923.6 15018.5 15194.8 15335.3 15664.0 1462.3 1500.9 1811.6 1813.9 1835.9 121067.4 125236.0 126747.0 127218.4 127401.0 10892.7 11171.9 11431.5 11595.9 11628.0 12665.4 13344.2 13372.2 14318.9 14333.9 6833.6 7954.6 8021.7 8416.0 8760.1 8276.6 8369.2 8389.8 8525.0 8711.5 5597.4 6710.2 6888.7 6893.7 6914.7 1910.8 1935.7 1936.0 1964.3 1992.9 776.0 917.4 964.8 971.3 986.4 21021.1 21729.0 21793.8 21941.3 21942.8 950.6 991.4 998.1 1000.8 1042.4 10693.2 11436.7 11560.1 11655.2 11713.8 607451.0 608262.0 609305.0 611090.0 632602.0 119297.4 119599.8 126074.0 152004.0 155117.8 902.3 1018.4 1018.6 1058.6 1069.2 16649.0 18253.3 18658.3 18826.6 19009.5 47431.0 55794.4 56250.0 56913.7 58300.7 83793.0 83981.2 84668.0 85623.0 86712.6 7078.6 7524.0 7628.0 7685.5 7787.8 12691.6 12859.3 13010.7 13258.9 13262.3 8975.6 10497.3 11276.9 11279.0 11404.1 10853.7 11174.6 11329.6 11489.2 11631.9 10838.6 11067.1 11399.5 11744.4 11786.7 8219.9 8259.0 8331.6 8383.2 8680.2 10823.3 11118.7 11129.6 11343.3 11477.1 08:21:36 INFO - PROCESS | 1679 | 08:21:36 INFO - PROCESS | 1679 | [#3] mootools.html Cycles:180 Average:5711.69 Median:4954.07 stddev:3219.18 (65.0%) stddev-sans-first:3211.87 08:21:36 INFO - PROCESS | 1679 | Values: 1395.4 1440.1 1601.0 1810.8 1982.7 7875.8 8480.2 8787.7 8792.3 8820.6 8341.9 8658.4 8668.7 8742.2 8750.6 1662.3 2134.6 2265.8 2380.2 2444.3 2215.7 2227.1 2377.6 2465.3 2468.6 2433.5 2438.7 2441.7 2443.4 2446.3 2374.9 2418.9 2423.8 2426.8 2428.6 2057.2 2121.2 2337.9 2426.9 2441.5 8565.0 8680.7 8682.4 8686.1 8692.0 5221.8 5341.1 5346.9 5348.6 5384.2 3414.7 3438.8 3445.6 3528.3 3537.5 963.4 969.6 985.0 1018.6 1022.4 11669.8 11900.9 11907.9 11912.4 11916.9 4798.7 4882.2 4973.6 4976.2 4996.8 10239.4 10247.7 10260.6 10263.7 10264.8 5428.8 5448.3 5479.2 5480.0 5481.2 4241.5 4432.3 4823.5 4924.2 4990.9 3242.9 3818.1 4049.7 4138.6 4164.0 1338.5 1355.6 1366.2 1367.7 1374.1 911.4 922.6 930.3 940.1 952.3 4840.5 5587.7 5744.0 5844.0 5949.5 947.2 964.8 975.2 977.2 996.7 3844.3 3892.2 3936.0 3968.6 4005.7 10336.6 10700.9 10749.4 10856.5 10867.9 10720.9 10810.5 10852.8 10861.2 10866.3 8197.0 8282.9 8293.6 8304.5 8312.1 5643.4 5715.8 5850.3 5866.2 5979.2 9722.4 9909.0 10196.3 10197.7 10222.0 9523.8 10214.9 10279.5 10299.9 10316.6 6770.7 6943.6 7135.4 7673.6 7678.3 6426.8 7807.1 8410.6 8414.1 8507.8 7388.2 7469.8 7487.6 7635.0 7653.8 7084.0 7339.3 7355.6 7367.7 7489.3 6979.8 7068.9 7078.5 7079.1 7081.0 4814.0 4879.7 4934.6 4993.1 5136.5 4884.2 4921.3 4993.5 5059.0 5200.8 08:21:36 INFO - PROCESS | 1679 | 08:21:36 INFO - PROCESS | 1679 | [#4] prototype.html Cycles:180 Average:4516.91 Median:5011.21 stddev:1403.04 (28.0%) stddev-sans-first:1371.03 08:21:36 INFO - PROCESS | 1679 | Values: 312.6 314.4 328.1 331.4 333.6 4446.3 4946.3 5216.6 5393.7 5400.4 4199.9 4907.0 4922.8 5215.7 5275.5 3340.6 3637.9 4064.2 4094.1 4113.2 3422.9 3609.1 3903.7 3986.0 4147.9 3415.3 3802.4 4070.5 4103.9 4129.3 3900.2 4043.0 4046.1 4053.7 4091.2 3522.0 3555.4 3564.7 3576.1 3579.6 4127.8 4188.8 4332.8 5003.9 5231.5 3680.0 4348.8 4563.5 4635.5 4669.3 4757.7 5264.4 5616.6 5627.6 5633.7 3284.8 3857.6 3900.3 3907.3 3908.7 4983.4 5626.8 5788.0 5903.4 5903.6 3656.5 3748.8 3919.3 3919.5 3939.1 3788.3 3930.3 3974.9 3982.0 4013.7 4033.5 4414.7 4423.8 4641.7 4659.6 5616.4 5625.4 5660.4 5673.7 5676.4 1212.2 1336.3 1355.2 1360.8 1362.1 803.4 811.7 839.7 840.6 873.6 4359.3 5209.8 5235.8 5273.7 5283.9 4869.3 5168.9 5739.9 5768.8 5777.0 4509.7 4961.5 5158.3 5259.7 5282.4 2565.3 2903.6 3013.2 3013.3 3037.4 5307.7 5550.4 5857.4 6017.2 6056.4 5344.0 5511.6 5709.8 6026.8 6056.6 6048.0 6050.9 6091.0 6095.9 6121.4 4816.7 5439.0 5838.3 5868.6 5871.2 5007.5 5390.9 5517.3 5776.2 5818.9 5599.2 5827.9 5949.5 5955.8 6150.0 3646.8 3908.3 3957.4 3972.7 3990.4 4803.7 5014.9 5061.8 5378.1 5936.5 4722.5 5602.8 5623.7 5651.4 5656.3 4518.4 5220.3 5602.4 5641.6 5691.5 5210.8 5500.3 5552.9 5600.9 5619.5 4611.4 5663.6 5680.7 5701.5 5702.9 5251.9 5402.3 5506.4 5577.7 5640.0 08:21:36 INFO - PROCESS | 1679 | 08:21:36 INFO - PROCESS | 1679 | [#5] yui.html Cycles:180 Average:17102.90 Median:567.60 stddev:23184.43 (4084.6%) stddev-sans-first:23216.90 08:21:36 INFO - PROCESS | 1679 | Values: 735.4 736.7 745.6 752.3 792.5 14991.3 15384.5 15619.5 15620.6 15631.8 14179.8 14236.9 14336.6 14656.0 14689.6 724.9 916.3 924.8 935.6 936.0 744.8 748.9 749.5 752.3 900.7 971.0 973.3 974.1 975.4 976.5 966.8 967.8 967.8 967.9 970.7 565.8 700.4 720.1 721.6 722.9 14985.8 15025.4 15032.5 15035.5 15055.9 12206.1 12289.3 12315.3 12548.5 12589.0 10307.3 10353.5 10421.2 10688.7 10921.7 3477.6 3983.7 4052.8 4088.7 4130.2 60451.0 74753.0 78325.6 78342.4 78522.4 9457.4 9457.9 9690.3 9704.9 9762.3 21406.6 21471.2 21471.7 21475.2 21495.0 7237.6 7393.2 7396.4 7397.0 7514.1 3934.4 3952.3 4070.0 4072.6 4106.9 3358.8 3470.8 3480.1 3483.7 3508.9 602.1 608.0 608.7 609.2 612.5 478.2 489.3 495.7 521.0 565.2 7324.1 7724.0 7745.8 7767.6 7831.3 462.6 479.4 537.8 562.4 569.4 3067.8 3524.5 3633.0 3669.2 3850.8 79272.6 79650.0 84911.0 86911.1 90023.0 65492.0 67395.0 68691.3 72480.0 73283.0 66606.3 66712.7 66900.0 68020.7 68729.0 8073.8 8085.1 8281.0 8294.0 8324.2 37281.0 38986.4 39554.8 40136.2 42806.7 58574.0 58707.1 60102.5 60447.0 61471.5 11143.8 11191.7 11279.3 11288.4 11509.9 11865.6 11930.3 12050.6 12064.0 13036.5 11489.3 11687.0 12110.3 12410.9 12412.5 11093.5 11116.0 11161.1 11214.4 12329.9 11270.9 11305.3 11306.2 11689.2 11789.3 9374.2 9384.7 9396.4 9669.7 10078.9 7450.0 9457.3 9571.0 9746.4 9847.6 08:21:36 INFO - PROCESS | 1679 | -------- Summary: end -------- 08:21:36 INFO - PROCESS | 1679 | 08:21:37 INFO - TEST-INFO | 1679: exit 0 08:21:37 INFO - TEST-OK | dromaeo_css | took 1115611ms 08:21:37 INFO - TEST-START | kraken 08:21:37 INFO - operating with platform_type : mac_ 08:21:37 INFO - Initialising browser for kraken test... 08:21:37 INFO - TEST-INFO | started process 1689 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpQsPILy/profile http://localhost:49277/getInfo.html) 08:21:39 INFO - PROCESS | 1689 | __metrics Screen width/height:1600/1200 08:21:39 INFO - PROCESS | 1689 | colorDepth:24 08:21:39 INFO - PROCESS | 1689 | Browser inner width/height: 1024/689 08:21:39 INFO - PROCESS | 1689 | __metrics 08:21:39 INFO - PROCESS | 1689 | 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" 08:21:39 ERROR - PROCESS | 1689 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:21:40 INFO - TEST-INFO | 1689: exit 0 08:21:40 INFO - Browser initialized. 08:21:40 INFO - Running cycle 1/1 for kraken test... 08:21:40 INFO - TEST-INFO | started process 1691 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpQsPILy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:21:52 INFO - PROCESS | 1691 | RSS: Main: 238891008 08:21:52 INFO - PROCESS | 1691 | 08:21:55 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 08:21:56 INFO - PROCESS | 1691 | RSS: Main: 287657984 08:21:56 INFO - PROCESS | 1691 | 08:21:59 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 08:21:59 INFO - PROCESS | 1691 | RSS: Main: 296243200 08:21:59 INFO - PROCESS | 1691 | 08:22:03 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 08:22:04 INFO - PROCESS | 1691 | RSS: Main: 387559424 08:22:04 INFO - PROCESS | 1691 | 08:22:06 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 08:22:06 INFO - PROCESS | 1691 | RSS: Main: 285319168 08:22:06 INFO - PROCESS | 1691 | 08:22:08 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:22:09 INFO - PROCESS | 1691 | RSS: Main: 289005568 08:22:09 INFO - PROCESS | 1691 | 08:22:12 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 08:22:13 INFO - PROCESS | 1691 | RSS: Main: 326701056 08:22:13 INFO - PROCESS | 1691 | 08:22:16 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 08:22:17 INFO - PROCESS | 1691 | RSS: Main: 329003008 08:22:17 INFO - PROCESS | 1691 | 08:22:20 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 08:22:20 INFO - PROCESS | 1691 | RSS: Main: 326598656 08:22:20 INFO - PROCESS | 1691 | 08:22:22 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:22:23 INFO - PROCESS | 1691 | RSS: Main: 316477440 08:22:23 INFO - PROCESS | 1691 | 08:22:25 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:22:25 INFO - PROCESS | 1691 | RSS: Main: 323108864 08:22:25 INFO - PROCESS | 1691 | 08:22:28 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:22:28 INFO - PROCESS | 1691 | RSS: Main: 324976640 08:22:28 INFO - PROCESS | 1691 | 08:22:31 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:22:32 INFO - PROCESS | 1691 | RSS: Main: 329781248 08:22:32 INFO - PROCESS | 1691 | 08:22:35 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:22:36 INFO - PROCESS | 1691 | RSS: Main: 327446528 08:22:36 INFO - PROCESS | 1691 | 08:22:37 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 08:22:38 INFO - PROCESS | 1691 | RSS: Main: 374554624 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | __start_tp_report 08:22:38 INFO - PROCESS | 1691 | _x_x_mozilla_page_load 08:22:38 INFO - PROCESS | 1691 | _x_x_mozilla_page_load_details 08:22:38 INFO - PROCESS | 1691 | |i|pagename|runs| 08:22:38 INFO - PROCESS | 1691 | |0;ai-astar;110;105;111;111;110;110;108;110;117;164 08:22:38 INFO - PROCESS | 1691 | |1;audio-beat-detection;153;117;138;195;117;136;135;117;165;117 08:22:38 INFO - PROCESS | 1691 | |2;audio-dft;185;185;180;184;183;183;183;184;183;183 08:22:38 INFO - PROCESS | 1691 | |3;audio-fft;79;74;57;125;58;66;57;59;110;56 08:22:38 INFO - PROCESS | 1691 | |4;audio-oscillator;92;101;133;79;80;77;102;80;79;101 08:22:38 INFO - PROCESS | 1691 | |5;imaging-gaussian-blur;90;86;78;113;134;111;88;84;88;83 08:22:38 INFO - PROCESS | 1691 | |6;imaging-darkroom;93;102;131;92;88;150;126;93;95;93 08:22:38 INFO - PROCESS | 1691 | |7;imaging-desaturate;86;86;87;107;137;90;137;133;96;86 08:22:38 INFO - PROCESS | 1691 | |8;json-parse-financial;60;61;62;59;60;59;58;119;57;58 08:22:38 INFO - PROCESS | 1691 | |9;json-stringify-tinderbox;62;63;67;76;73;68;68;71;67;67 08:22:38 INFO - PROCESS | 1691 | |10;stanford-crypto-aes;86;85;88;71;84;96;109;108;107;86 08:22:38 INFO - PROCESS | 1691 | |11;stanford-crypto-ccm;141;134;149;128;143;145;149;141;217;174 08:22:38 INFO - PROCESS | 1691 | |12;stanford-crypto-pbkdf2;170;167;167;165;166;166;166;167;168;166 08:22:38 INFO - PROCESS | 1691 | |13;stanford-crypto-sha256-iterative;67;67;68;68;68;66;66;65;66;65 08:22:38 INFO - PROCESS | 1691 | __end_tp_report 08:22:38 INFO - PROCESS | 1691 | __start_cc_report 08:22:38 INFO - PROCESS | 1691 | _x_x_mozilla_cycle_collect,2276 08:22:38 INFO - PROCESS | 1691 | __end_cc_report 08:22:38 INFO - PROCESS | 1691 | __startTimestamp1463066558124__endTimestamp 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | ------- Summary: start ------- 08:22:38 INFO - PROCESS | 1691 | Number of tests: 14 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#0] ai-astar Cycles:10 Average:115.60 Median:110.50 stddev:17.26 (15.6%) stddev-sans-first:18.19 08:22:38 INFO - PROCESS | 1691 | Values: 110.0 105.0 111.0 111.0 110.0 110.0 108.0 110.0 117.0 164.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:137.00 stddev:25.76 (18.8%) stddev-sans-first:26.81 08:22:38 INFO - PROCESS | 1691 | Values: 153.0 117.0 138.0 195.0 117.0 136.0 135.0 117.0 165.0 117.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.42 (0.8%) stddev-sans-first:1.36 08:22:38 INFO - PROCESS | 1691 | Values: 185.0 185.0 180.0 184.0 183.0 183.0 183.0 184.0 183.0 183.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#3] audio-fft Cycles:10 Average:74.10 Median:58.50 stddev:24.42 (41.8%) stddev-sans-first:25.84 08:22:38 INFO - PROCESS | 1691 | Values: 79.0 74.0 57.0 125.0 58.0 66.0 57.0 59.0 110.0 56.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:79.00 stddev:17.61 (22.3%) stddev-sans-first:18.68 08:22:38 INFO - PROCESS | 1691 | Values: 92.0 101.0 133.0 79.0 80.0 77.0 102.0 80.0 79.0 101.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:85.00 stddev:17.82 (21.0%) stddev-sans-first:18.78 08:22:38 INFO - PROCESS | 1691 | Values: 90.0 86.0 78.0 113.0 134.0 111.0 88.0 84.0 88.0 83.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#6] imaging-darkroom Cycles:10 Average:106.30 Median:92.50 stddev:21.41 (23.1%) stddev-sans-first:22.16 08:22:38 INFO - PROCESS | 1691 | Values: 93.0 102.0 131.0 92.0 88.0 150.0 126.0 93.0 95.0 93.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#7] imaging-desaturate Cycles:10 Average:104.50 Median:86.00 stddev:22.48 (26.1%) stddev-sans-first:22.82 08:22:38 INFO - PROCESS | 1691 | Values: 86.0 86.0 87.0 107.0 137.0 90.0 137.0 133.0 96.0 86.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:59.50 stddev:18.93 (31.8%) stddev-sans-first:19.98 08:22:38 INFO - PROCESS | 1691 | Values: 60.0 61.0 62.0 59.0 60.0 59.0 58.0 119.0 57.0 58.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.00 stddev:4.24 (6.2%) stddev-sans-first:3.86 08:22:38 INFO - PROCESS | 1691 | Values: 62.0 63.0 67.0 76.0 73.0 68.0 68.0 71.0 67.0 67.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#10] stanford-crypto-aes Cycles:10 Average:92.00 Median:85.50 stddev:12.60 (14.7%) stddev-sans-first:13.17 08:22:38 INFO - PROCESS | 1691 | Values: 86.0 85.0 88.0 71.0 84.0 96.0 109.0 108.0 107.0 86.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:147.00 stddev:25.82 (17.6%) stddev-sans-first:27.07 08:22:38 INFO - PROCESS | 1691 | Values: 141.0 134.0 149.0 128.0 143.0 145.0 149.0 141.0 217.0 174.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:167.00 stddev:1.40 (0.8%) stddev-sans-first:0.88 08:22:38 INFO - PROCESS | 1691 | Values: 170.0 167.0 167.0 165.0 166.0 166.0 166.0 167.0 168.0 166.0 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - PROCESS | 1691 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.17 (1.8%) stddev-sans-first:1.24 08:22:38 INFO - PROCESS | 1691 | Values: 67.0 67.0 68.0 68.0 68.0 66.0 66.0 65.0 66.0 65.0 08:22:38 INFO - PROCESS | 1691 | -------- Summary: end -------- 08:22:38 INFO - PROCESS | 1691 | 08:22:38 INFO - TEST-INFO | 1691: exit 0 08:22:38 INFO - TEST-OK | kraken | took 61500ms 08:22:38 INFO - SUITE-END | took 1177s 08:22:39 INFO - Completed test suite (00:19:37) 08:22:39 INFO - javascript benchmark 08:22:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2830.0, 2954.759001219488, 3432.124808173916, 3467.4625433057886, 3502.684758371746, 5654.943450565494, 5674.773009079642, 5785.797497087603, 5793.942060579393, 5846.298444186693, 5673.744681489333, 5736.885262294752, 5798.449147331007, 5830.0380437227805, 5879.735411906464, 5139.820106296281, 5184.2223666450145, 5244.947550524484, 5261.973690131563, 5330.3603567571945, 5302.655327403726, 5343.278657381262, 5346.010987967219, 5354.625176237665, 5373.355197376301, 5141.254518094893, 5154.484551544853, 5207.791688332462, 5241.056609810233, 5241.528262456378, 5177.326947496839, 5192.350956130484, 5195.818146364897, 5266.394364648077, 5301.363836339626, 5181.8336179945745, 5196.714180720059, 5243.239730239094, 5270.736463176826, 5273.209018647195, 5519.282493275889, 5567.916481252784, 5611.270534830487, 5731.598788084836, 5831.125331200312, 5221.2951251580935, 6167.229096362955, 6205.22434695663, 6226.097215903668, 6230.7819226326865, 6136.969315153396, 6182.1345011698395, 6183.814485565395, 6211.09939058834, 6213.937860621426, 5703.743331550048, 5776.1624564437925, 5794.1308803679785, 5850.736716847752, 5882.411758824126, 6223.96888015557, 6287.49700023997, 6312.0, 6327.209098862642, 6329.68351582419, 6193.349698281706, 6194.5973511721595, 6198.6280823150755, 6213.565050446426, 6218.689065546704, 6067.544934129958, 6175.660338681326, 6188.4739797116845, 6207.844803879939, 6216.0, 6188.907166392508, 6203.751849925952, 6264.1856558648, 6283.560150789401, 6285.214348206474, 5952.285725712855, 6200.3179650238435, 6232.0, 6259.874802504016, 6342.587731797418, 4820.469748327681, 4860.246661767432, 4885.145099607555, 4933.383327084114, 4964.751762411865, 4388.2759344707765, 4392.780360981938, 4412.117576484652, 4435.423394958635, 4477.1493416250805, 5825.533957283407, 5847.26909136358, 5854.385289544622, 5858.5898987070495, 5874.236349274653, 6154.846128846814, 6195.380461953859, 6222.657753823583, 6273.12176295319, 6285.025820997753, 5814.011618024946, 5836.970815145982, 5839.649621022752, 5868.061110222344, 5874.911876321773, 5396.541293989976, 5422.078246698071, 5430.70131142791, 5433.646812957145, 5435.103207970719, 6228.906566401508, 6262.405071518289, 6315.747370105144, 6328.493720502441, 6388.105665206875, 6290.496760259077, 6297.244330680348, 6298.622082675054, 6299.464545513682, 6329.240491141092, 6377.0, 6399.168108145911, 6404.71178796946, 6414.5189110815945, 6415.871682566416, 6094.54290928191, 6104.17593624855, 6135.508280839814, 6212.720427580679, 6215.502759779117, 6250.812475625738, 6256.405641464054, 6291.591046581958, 6298.307186209605, 6307.211598550181, 6140.938590614037, 6186.659733714689, 6187.907181392193, 6190.81427557174, 6203.100550420254, 5986.431289027535, 5992.5205983520345, 6001.489873360813, 6053.182820319201, 6164.291106522807, 5034.320366750589, 6028.87942241144, 6045.6070355426755, 6063.72713227897, 6067.211262535842, 6157.415045570664, 6172.567920245539, 6175.166352542529, 6183.907241391473, 6205.751769929152, 5791.7683292667825, 5888.823335299948, 5946.07835785454, 5968.641881487125, 6007.219061522149], "value": 5714.469896185313, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.73921191374524, 515.5488947171225, 518.541091134346, 531.7314756048193, 553.6318348298382, 27058.729412705867, 42309.94225144369, 43534.34698479525, 43569.56430435694, 46234.76882615582, 44393.0, 44766.10467790651, 44816.879578010565, 44859.0, 44922.77538612302, 8598.613062412176, 9698.0, 9881.505924703772, 9912.107910288072, 9919.256055795808, 8691.695790647329, 11597.652070437858, 11813.291202527833, 11853.0, 11973.820392694117, 11836.349000804952, 11882.643520694393, 11889.524419023228, 11918.523259069627, 11922.642320730392, 8702.216800487924, 11714.062874969983, 11728.237664551776, 11884.821727674049, 11971.341576213305, 11742.0, 11801.822972655416, 11813.054955603531, 11815.172937894351, 11845.0, 39335.21329573393, 39737.00657483586, 39743.205135897406, 39757.60242397568, 39832.20335593272, 3173.873045078194, 3213.5822343095483, 3267.248532837437, 3268.967310326914, 3395.47370157626, 23031.0, 28891.855540722372, 29254.0, 29616.25959351012, 29986.800527979074, 2776.41695243999, 2826.4771017361854, 2854.215090850012, 2856.6143570618115, 2859.25659328574, 377715.0, 379980.4003919906, 380081.099594503, 380807.0, 380903.0, 58677.0, 60546.0, 60675.0, 60716.0, 60732.0, 30172.0, 30400.54399184014, 37431.62568374336, 37552.87341379763, 39071.0, 16229.837701622833, 16321.34714611426, 16343.101129437757, 16368.918155409146, 16378.836211637969, 11390.829137562943, 11582.0, 11600.6519804406, 11798.351090690094, 12325.322107284186, 14086.366113524917, 19496.363844518695, 20385.286514971904, 20566.58866822655, 20567.58864822725, 7874.0, 7983.600819958979, 7984.041914970241, 7997.966365276763, 8031.477953932976, 955.8757361542955, 1056.963006294776, 1119.2724728926264, 1150.9021733152777, 1187.6555798818247, 24950.875245623654, 29528.409431811673, 29674.703252967192, 30647.540286895714, 30862.537061944087, 1056.1814593689799, 1085.5440714899762, 1135.8125909224884, 1151.5739176504746, 1162.3897453836803, 13604.843588294907, 14052.297385130703, 15406.537803865678, 15715.21423928799, 15834.445794397141, 663534.0, 667014.0, 667363.0, 670099.0, 671087.0, 56531.86936261334, 60338.69830650819, 60419.69790151021, 60424.39575604276, 60645.69677151586, 1297.3967105296083, 1335.5192130833086, 1336.8128462015131, 1442.343733601209, 1463.5024091808837, 29613.03845829803, 31428.685713142575, 31721.8413907929, 31763.0, 32128.518072229403, 55100.0, 55497.0, 56002.0, 56124.1937903095, 56124.719376402856, 485752.0, 539009.0, 546877.0, 547167.0, 549341.0, 22115.557688846457, 23632.0, 24285.51428971446, 26891.327716807235, 27307.590386143827, 42922.35616465693, 45485.09029819451, 46477.767611161726, 47225.527744722116, 47774.0, 37001.0, 37071.81464092662, 37174.628253717114, 37723.81138094292, 37819.62180378161, 36771.20648193373, 37238.0, 37740.81129594334, 37932.43101353537, 38133.0, 36727.632723672425, 37040.81479592585, 37041.62958370382, 37208.140092755086, 38194.0, 32104.83947580247, 32842.343153137284, 33781.49327760036, 34079.318413632085, 34962.47556286708, 24094.0, 25196.49607007886, 25238.0, 25701.0, 25841.612375814002], "value": 19857.444230193996, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.1923019349946, 394.08375526899965, 394.57780175212525, 399.5046142782948, 417.8391319342052, 15040.097594144332, 17051.914740426295, 19153.425397238105, 19853.404397868067, 20884.269050583232, 21034.68447973278, 21097.789022109773, 21124.155033798666, 21277.787222127776, 21625.243116490925, 5749.195112684217, 5976.193213916109, 6053.576249662503, 6056.242969628796, 6060.757569697207, 5804.312601271345, 5958.5828991970575, 5969.164316995624, 6036.879262414749, 6046.3349031606485, 5953.940460595382, 5968.850778730544, 5979.312379076397, 5983.910241346383, 5993.951058564734, 5766.826995190151, 5871.530277577805, 5940.168376427304, 5953.255843019622, 5979.0, 4430.446194225722, 5130.358705161855, 5150.459201783834, 5156.845294641167, 5198.792048318062, 24544.0, 25343.746562534325, 25618.87190564054, 25626.48747025049, 25805.61291580628, 2069.6791997240452, 2073.8237249833783, 2261.8642881427004, 2290.9541809163725, 2331.825113116506, 14923.552293431214, 15018.474353397578, 15194.848051519453, 15335.30991105403, 15664.0, 1462.3273294284643, 1500.8574185452474, 1811.5544015578864, 1813.9002354870477, 1835.9082045897785, 121067.39466302701, 125235.99999999908, 126747.0, 127218.36390818079, 127401.0, 10892.727681808019, 11171.888281117248, 11431.542738290542, 11595.884041159481, 11628.0, 12665.36673166338, 13344.199348039148, 13372.197668139943, 14318.85681143196, 14333.856661433252, 6833.555818871758, 7954.562499062491, 8021.719239826695, 8416.0, 8760.08019157992, 8276.586170691442, 8369.246767790928, 8389.783481395163, 8525.0, 8711.47731136134, 5597.44025597437, 6710.188249172794, 6888.724451022003, 6893.724251029903, 6914.723411063602, 1910.7516022916932, 1935.6709359408935, 1935.9903200484189, 1964.2634012245408, 1992.85053620979, 775.9922400776032, 917.4357769971552, 964.81668482988, 971.2715463402449, 986.363795351994, 21021.05405256767, 21729.022194001307, 21793.782062179493, 21941.341759746912, 21942.78057219439, 950.6054987180381, 991.3556188477547, 998.1116806042628, 1000.7948370584013, 1042.4318746283293, 10693.19801014927, 11436.714082148013, 11560.075193984461, 11655.184137110491, 11713.824292635618, 607451.0, 608262.0, 609305.0, 611090.0, 632602.0, 119297.40351298361, 119599.80400196061, 126073.96991138947, 152004.0, 155117.8149857252, 902.2601466797295, 1018.3584341864578, 1018.5772904244656, 1058.5501162006117, 1069.2087854987253, 16649.001059936443, 18253.269869205084, 18658.253669853053, 18826.623467530848, 19009.524761881064, 47431.0, 55794.442055578926, 56250.0, 56913.71543142258, 58300.70849645725, 83793.0, 83981.16018839978, 84668.0, 85623.0, 86712.5664371674, 7078.610676412641, 7524.021877155935, 7628.0, 7685.538867667958, 7787.805304867424, 12691.555795547112, 12859.26399936034, 13010.739785204432, 13258.86741132615, 13262.27057511846, 8975.596098175729, 10497.265191436527, 11276.9436152822, 11279.041281491165, 11404.087672985976, 10853.728656783644, 11174.608888689181, 11329.603463878724, 11489.195756296978, 11631.941840290745, 10838.620648277272, 11067.059299959594, 11399.544018239178, 11744.412779361168, 11786.705332366759, 8219.917800821915, 8259.0, 8331.583420828812, 8383.245507904317, 8680.175383338572, 10823.296485728402, 11118.666440006813, 11129.610463633733, 11343.262687925588, 11477.139214558774], "value": 9803.898756100645, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.3720825628466, 1440.092741572809, 1600.991995040025, 1810.7827060752704, 1982.6629472989591, 7875.803104922373, 8480.151984801525, 8787.692430764933, 8792.2966162707, 8820.647174113028, 8341.91658083419, 8658.393912426118, 8668.653253869854, 8742.169493898069, 8750.606222719976, 1662.3184494357315, 2134.6050980568566, 2265.844419346137, 2380.1907351500486, 2444.2545023767743, 2215.700880381144, 2227.0757635581213, 2377.5720370333333, 2465.3097132802845, 2468.5556599812026, 2433.549793288247, 2438.670779444779, 2441.7436169202247, 2443.3769388805918, 2446.2905757330354, 2374.9050037998545, 2418.8790560471907, 2423.7818596326415, 2426.793722533578, 2428.550718117154, 2057.2182570623067, 2121.1727426303755, 2337.906483740648, 2426.8786560672074, 2441.523902838947, 8565.0, 8680.739577812676, 8682.43564168327, 8686.131386861327, 8692.0, 5221.843344699665, 5341.1187154119525, 5346.893062138774, 5348.572114230852, 5384.246205531229, 3414.709749671281, 3438.793672379665, 3445.586529616437, 3528.311979164064, 3537.506058228968, 963.393062370709, 969.5830792759111, 985.0, 1018.5823812237021, 1022.4223313827664, 11669.824952625717, 11900.940495297555, 11907.940460297643, 11912.404379780975, 11916.880831191664, 4798.664093513421, 4882.194437917704, 4973.55238028572, 4976.154053810861, 4996.7501624918605, 10239.436830974219, 10247.743806404751, 10260.640877569249, 10263.7434064149, 10264.846027309597, 5428.75570599324, 5448.318960129984, 5479.205515200353, 5480.04099282624, 5481.205225242317, 4241.491021077491, 4432.2908334666945, 4823.541763532458, 4924.236743304794, 4990.850274491771, 3242.854071566785, 3818.140918293417, 4049.716519843583, 4138.627523522898, 4163.97918010408, 1338.4713038349992, 1355.627202519315, 1366.2485632901864, 1367.6870204603492, 1374.093098554949, 911.40758506971, 922.6217250927222, 930.316217580078, 940.05994005994, 952.3048174832411, 4840.515948405202, 5587.69267690281, 5744.0, 5844.035734103911, 5949.524038076944, 947.2090804178588, 964.840801267797, 975.2441857560306, 977.237754551451, 996.7009897031006, 3844.3272427325105, 3892.163184915257, 3935.976646072042, 3968.6031396860085, 4005.6595189409227, 10336.638217662496, 10700.89299106999, 10749.40878251688, 10856.511456984455, 10867.891321086689, 10720.946395268129, 10810.513526891309, 10852.837207441737, 10861.1854110942, 10866.348019118877, 8196.959015204886, 8282.917170828336, 8293.626786794608, 8304.460210086325, 8312.127226641236, 5643.35101463337, 5715.771369145187, 5850.268716410449, 5866.208061911588, 5979.192808970907, 9722.368046077265, 9908.959559246463, 10196.286259961731, 10197.694069177936, 10222.0, 9523.761910951533, 10214.897851021691, 10279.537420815932, 10299.867014628235, 10316.587336506456, 6770.729170833111, 6943.583384996917, 7135.393491553276, 7673.616319183907, 7678.270564296383, 6426.839329016812, 7807.141214466291, 8410.579471026547, 8414.116517765546, 8507.787305317417, 7388.1503627083575, 7469.813254668677, 7487.5507469552, 7634.961825190839, 7653.846923061396, 7084.0, 7339.2660733928315, 7355.5586664800285, 7367.7421290256725, 7489.28851759082, 6979.755708550177, 7068.893966590608, 7078.4691148163065, 7079.115110611174, 7080.964595176991, 4813.975930120327, 4879.731614761222, 4934.580560652241, 4993.051320249141, 5136.460671629575, 4884.218525035977, 4921.261810728276, 4993.500649935123, 5058.974705126451, 5200.817971370984], "value": 4590.744474960973, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.5639732573059, 314.35870823520014, 328.0928233434554, 331.3836264547942, 333.5946824608102, 4446.266366049598, 4946.3322451469085, 5216.60875434343, 5393.73031348433, 5400.378956420013, 4199.916001679972, 4906.975465122669, 4922.80308787648, 5215.748220427098, 5275.49882761138, 3340.5824271966003, 3637.9090522736947, 4064.166845796605, 4094.078832262747, 4113.161918276394, 3422.897313080599, 3609.1338078861017, 3903.7072219583647, 3985.980070099645, 4147.917041659165, 3415.3189746049356, 3802.391617341226, 4070.5318888327924, 4103.917921641565, 4129.298019336705, 3900.161465284963, 4042.959570404317, 4046.1300820323477, 4053.7365071270274, 4091.243120022805, 3521.964780352215, 3555.3600351936643, 3564.697000754939, 3576.1059735066233, 3579.624139465345, 4127.834886604533, 4188.832446702128, 4332.805023773938, 5003.924941125886, 5231.450697676727, 3679.9816000919823, 4348.826046958118, 4563.520830312803, 4635.490096089427, 4669.346291519156, 4757.738324392157, 5264.394594621629, 5616.63491873031, 5627.577931655102, 5633.6619802811965, 3284.753643476749, 3857.57269810172, 3900.2979463696524, 3907.2966865964113, 3908.667763240128, 4983.426905905831, 5626.774929002835, 5788.044972579477, 5903.439173278532, 5903.586748927577, 3656.4880916671686, 3748.83130259139, 3919.3141200289833, 3919.451276821247, 3939.113699417608, 3788.318102741533, 3930.331843586597, 3974.8608798691907, 3981.969195710151, 4013.7190396671954, 4033.515978082649, 4414.668899832525, 4423.778811059434, 4641.721496710208, 4659.58063774262, 5616.410276920946, 5625.409332020161, 5660.377358490563, 5673.659580425187, 5676.4323567642905, 1212.2484059882752, 1336.3051213369188, 1355.1868878672915, 1360.823092997904, 1362.1078193783087, 803.4135081390617, 811.679386642273, 839.7068514487621, 840.5923127283263, 873.6330741088758, 4359.346098085249, 5209.7916083356995, 5235.842924712265, 5273.681579605099, 5283.894322113536, 4869.342638743795, 5168.888688931897, 5739.85650358736, 5768.826935191951, 5777.0, 4509.661775366795, 4961.5286547777905, 5158.277841102249, 5259.737013149327, 5282.385219316463, 2565.3073670108965, 2903.637045369329, 3013.2316259353724, 3013.2918764090423, 3037.39252149571, 5307.734613269321, 5550.393173665848, 5857.4435428634215, 6017.217761690952, 6056.424639659225, 5343.973280133574, 5511.559075274049, 5709.800941802185, 6026.849328766816, 6056.606320589148, 6047.971844786397, 6050.850338435683, 6090.969545152246, 6095.9273846892265, 6121.4490695837585, 4816.735079570657, 5438.972805135949, 5838.299404071538, 5868.618539794899, 5871.207387002871, 5007.524285192901, 5390.867917737319, 5517.310336207974, 5776.249087618583, 5818.9127163093435, 5599.22838195096, 5827.9417205829095, 5949.494292985145, 5955.821325360246, 6150.0, 3646.762960407565, 3908.2769687607883, 3957.4459575658857, 3972.7020473464027, 3990.38149086892, 4803.735794531194, 5014.899702006012, 5061.822836200862, 5378.085725426608, 5936.495397891227, 4722.480527141943, 5602.831915042555, 5623.718814059199, 5651.4348565143155, 5656.264685591012, 4518.43519560055, 5220.295260139833, 5602.431319230622, 5641.605087643826, 5691.4877661010705, 5210.791568337376, 5500.257465242141, 5552.944470555404, 5600.915986260291, 5619.466150715675, 4611.354410382616, 5663.57523185771, 5680.6591604503865, 5701.486866182064, 5702.942970570241, 5251.89496210066, 5402.297701298806, 5506.421825708403, 5577.749001295033, 5639.96961094014], "value": 4074.515074999895, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.3896266099136, 736.675862620447, 745.6495447139843, 752.2778132992327, 792.5442870349549, 14991.250437478124, 15384.461543845968, 15619.531414057568, 15620.609484762872, 15631.765523517157, 14179.78730319046, 14236.857631423683, 14336.569902902904, 14656.0, 14689.559313220621, 724.8731471992406, 916.2898753466051, 924.8381533231691, 935.6210734652477, 935.976600584984, 744.7505085796265, 748.8726916424221, 749.4978364495773, 752.3229093815582, 900.7387857521311, 970.9854352184723, 973.3478569358547, 974.098958463422, 975.4244995452682, 976.5215044628152, 966.7873067925045, 967.7822489939778, 967.8451447768358, 967.88869280033, 970.6699722094522, 565.7934853778382, 700.4116542104606, 720.103471178381, 721.6319676964786, 722.9457790665668, 14985.775213371808, 15025.398984040625, 15032.549023529311, 15035.54893353201, 15055.924720376437, 12206.145569810116, 12289.324087175202, 12315.261084334968, 12548.498060077585, 12589.0, 10307.33002354852, 10353.534090965924, 10421.166306695446, 10688.679339619746, 10921.726956826064, 3477.565304336958, 3983.7410568313257, 4052.837886484537, 4088.6524645405175, 4130.153318569686, 60451.0, 74753.0, 78325.55907188865, 78342.43315133665, 78522.42955140866, 9457.38526995743, 9457.90542094584, 9690.273229507815, 9704.902950970541, 9762.267829912786, 21406.57186856254, 21471.248506302203, 21471.67792483114, 21475.248366307103, 21495.0, 7237.63811809418, 7393.223711510322, 7396.408287337, 7397.0, 7514.135874374517, 3934.370500719871, 3952.288588054178, 4069.9796501017304, 4072.572379900127, 4106.876793696194, 3358.781679190845, 3470.8091054991714, 3480.147363895811, 3483.7038851697384, 3508.8771892983623, 602.1239097113689, 607.9878402431926, 608.716946619824, 609.20194545146, 612.4916319454842, 478.21333905725584, 489.300300570188, 495.7100096443549, 521.0048806779034, 565.1522715926111, 7324.121105467378, 7724.0, 7745.8450830983065, 7767.572783496962, 7831.334336581454, 462.6213882629782, 479.4031430868591, 537.7677676684518, 562.3734535967699, 569.4078158714973, 3067.8466076696523, 3524.54180956474, 3633.0, 3669.2478042001326, 3850.8074596270076, 79272.60363698144, 79650.0, 84911.0, 86911.1308886903, 90022.98713317579, 65492.0, 67394.98907516392, 68691.3130868685, 72480.0, 73283.0, 66606.33484162934, 66712.66229708267, 66900.0, 68020.6598967002, 68729.0, 8073.798155046171, 8085.110637829952, 8281.047679516932, 8294.0, 8324.209200125979, 37281.0, 38986.415203772536, 39554.802225988686, 40136.197276054896, 42806.71579852609, 58574.0, 58707.11939320828, 60102.49743756441, 60447.0, 61471.46321342002, 11143.83284250753, 11191.72020699489, 11279.266847655204, 11288.363187337625, 11509.942450287695, 11865.644030679094, 11930.284182949052, 12050.638480845588, 12064.0, 13036.543720969721, 11489.310641361544, 11687.0, 12110.333931633493, 12410.937945310216, 12412.503499859904, 11093.54322278777, 11116.037843950984, 11161.06352428572, 11214.439278036229, 12329.93835030855, 11270.887291126985, 11305.321680699184, 11306.208565400342, 11689.181757277249, 11789.292642441482, 9374.203192728695, 9384.67153649619, 9396.436213827192, 9669.70990870275, 10078.873379612409, 7450.0, 9457.290703197425, 9571.0, 9746.366486178376, 9847.60609575609], "value": 6716.380326248995, "unit": "score"}], "name": "dromaeo_css", "value": 7203.990377301083, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 111.0, 111.0, 110.0, 110.0, 108.0, 110.0, 117.0, 164.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 117.0, 138.0, 195.0, 117.0, 136.0, 135.0, 117.0, 165.0, 117.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 180.0, 184.0, 183.0, 183.0, 183.0, 184.0, 183.0, 183.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 57.0, 125.0, 58.0, 66.0, 57.0, 59.0, 110.0, 56.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 101.0, 133.0, 79.0, 80.0, 77.0, 102.0, 80.0, 79.0, 101.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 78.0, 113.0, 134.0, 111.0, 88.0, 84.0, 88.0, 83.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 102.0, 131.0, 92.0, 88.0, 150.0, 126.0, 93.0, 95.0, 93.0], "value": 106.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 107.0, 137.0, 90.0, 137.0, 133.0, 96.0, 86.0], "value": 104.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 62.0, 59.0, 60.0, 59.0, 58.0, 119.0, 57.0, 58.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 67.0, 76.0, 73.0, 68.0, 68.0, 71.0, 67.0, 67.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 88.0, 71.0, 84.0, 96.0, 109.0, 108.0, 107.0, 86.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 134.0, 149.0, 128.0, 143.0, 145.0, 149.0, 141.0, 217.0, 174.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 167.0, 165.0, 166.0, 166.0, 166.0, 167.0, 168.0, 166.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 68.0, 68.0, 66.0, 66.0, 65.0, 66.0, 65.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1521.6999999999996, "alertThreshold": 2.0}]} 08:22:39 INFO - Return code: 0 08:22:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:22:39 ERROR - # TBPL SUCCESS # 08:22:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:22:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:22:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:22:39 INFO - 2016-05-12 08:22:39.444 screenresolution[1695:9954] starting screenresolution argv=screenresolution get 08:22:39 INFO - 2016-05-12 08:22:39.447 screenresolution[1695:9954] Display 0: 1600x1200x32@60 08:22:39 INFO - 2016-05-12 08:22:39.453 screenresolution[1696:9957] starting screenresolution argv=screenresolution list 08:22:39 INFO - Available Modes on Display 0 08:22:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:22:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:22:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:22:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:22:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:22:39 INFO - Intel Iris: 08:22:39 INFO - Chipset Model: Intel Iris 08:22:39 INFO - Type: GPU 08:22:39 INFO - Bus: Built-In 08:22:39 INFO - VRAM (Dynamic, Max): 1536 MB 08:22:39 INFO - Vendor: Intel (0x8086) 08:22:39 INFO - Device ID: 0x0a2e 08:22:39 INFO - Revision ID: 0x0009 08:22:39 INFO - Displays: 08:22:39 INFO - Display: 08:22:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:22:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:22:39 INFO - Display Serial Number: 1600x1200 60 08:22:39 INFO - Main Display: Yes 08:22:39 INFO - Mirror: Off 08:22:39 INFO - Online: Yes 08:22:39 INFO - Rotation: Supported 08:22:39 INFO - Return code: 0 08:22:39 INFO - Running post-action listener: _resource_record_post_action 08:22:39 INFO - ##### 08:22:39 INFO - ##### Finished run-tests step (success) 08:22:39 INFO - ##### 08:22:39 INFO - Running post-run listener: _resource_record_post_run 08:22:40 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37713408; Write bytes: 693649920; Read time: 397; Write time: 1983 08:22:40 INFO - TinderboxPrint: CPU usage
53.6% 08:22:40 INFO - TinderboxPrint: I/O read bytes / time
37,713,408 / 397 08:22:40 INFO - TinderboxPrint: I/O write bytes / time
693,649,920 / 1,983 08:22:40 INFO - TinderboxPrint: CPU idle
2,248.6 (46.4%) 08:22:40 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 08:22:40 INFO - TinderboxPrint: CPU user
1,631.3 (33.6%) 08:22:40 INFO - TinderboxPrint: Swap in / out
431,620,096 / 0 08:22:40 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 390375936; Write bytes: 384824832; Read time: 28849; Write time: 730 08:22:40 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35870208; Write bytes: 295721984; Read time: 374; Write time: 1216 08:22:40 INFO - Running post-run listener: _upload_blobber_files 08:22:40 INFO - Blob upload gear active. 08:22:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:22:40 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 08:22:40 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'] 08:22:40 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 08:22:41 INFO - (blobuploader) - INFO - Open directory for files ... 08:22:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:22:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:22:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:42 INFO - (blobuploader) - INFO - Done attempting. 08:22:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:22:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:22:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:43 INFO - (blobuploader) - INFO - Done attempting. 08:22:43 INFO - (blobuploader) - INFO - Iteration through files over. 08:22:43 INFO - Return code: 0 08:22:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:22:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:22:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60375d90b05d96963deba8d4204213863e65ef2ad7b931072291bceaa0816de8b4232ce7a25e621f769ec3e1796adabc6f4ad1f016039610187a274d6b3140e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95f77a1bb5dbc32e773b5db1410a18e15d3496bacec73ab895ba03242120b50206d01c19c43a5bff47fe0c2f7a222c5a403caf170b2230eb186720bc5599c60d"} 08:22:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:22:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:22:43 INFO - Contents: 08:22:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60375d90b05d96963deba8d4204213863e65ef2ad7b931072291bceaa0816de8b4232ce7a25e621f769ec3e1796adabc6f4ad1f016039610187a274d6b3140e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95f77a1bb5dbc32e773b5db1410a18e15d3496bacec73ab895ba03242120b50206d01c19c43a5bff47fe0c2f7a222c5a403caf170b2230eb186720bc5599c60d"} 08:22:43 INFO - Running post-run listener: copy_logs_to_upload_dir 08:22:43 INFO - Copying logs to upload dir... 08:22:43 INFO - mkdir: /builds/slave/test/build/upload/logs 08:22:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.446031 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-12 08:22:43.381695) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 08:22:43.385063) ========= 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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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/60375d90b05d96963deba8d4204213863e65ef2ad7b931072291bceaa0816de8b4232ce7a25e621f769ec3e1796adabc6f4ad1f016039610187a274d6b3140e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95f77a1bb5dbc32e773b5db1410a18e15d3496bacec73ab895ba03242120b50206d01c19c43a5bff47fe0c2f7a222c5a403caf170b2230eb186720bc5599c60d"} build_url:https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/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/60375d90b05d96963deba8d4204213863e65ef2ad7b931072291bceaa0816de8b4232ce7a25e621f769ec3e1796adabc6f4ad1f016039610187a274d6b3140e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95f77a1bb5dbc32e773b5db1410a18e15d3496bacec73ab895ba03242120b50206d01c19c43a5bff47fe0c2f7a222c5a403caf170b2230eb186720bc5599c60d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 08:22:43.412517) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:22:43.412843) ========= 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.xKHrZIwcDj/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.9SCcPYdvL2/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.004568 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:22:43.460415) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 08:22:43.460729) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 08:22:43.461039) ========= ========= Total master_lag: 2.16 =========