builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0115 starttime: 1462700615.44 results: success (0) buildid: 20160508004657 builduid: a2f52d406a154f0fb05c90c5a75d9e4b revision: a3d59df2a8e356daae28128310db872d61c01f8f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:35.439625) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:35.440008) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:35.440272) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:35.537171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:35.537464) ========= 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.zU1sYfp61i/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.834nK67YRB/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-08 02:43:35-- 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% 8.48M=0.001s 2016-05-08 02:43:35 (8.48 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115024 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:35.781495) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:35.782055) ========= 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.zU1sYfp61i/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.834nK67YRB/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.030648 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:35.864437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-08 02:43:35.864784) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a3d59df2a8e356daae28128310db872d61c01f8f --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 a3d59df2a8e356daae28128310db872d61c01f8f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zU1sYfp61i/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.834nK67YRB/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-08 02:43:35,942 truncating revision to first 12 chars 2016-05-08 02:43:35,942 Setting DEBUG logging. 2016-05-08 02:43:35,942 attempt 1/10 2016-05-08 02:43:35,942 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a3d59df2a8e3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-08 02:43:37,849 unpacking tar archive at: fx-team-a3d59df2a8e3/testing/mozharness/ program finished with exit code 0 elapsedTime=2.157698 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-08 02:43:38.042413) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:38.045703) ========= script_repo_revision: a3d59df2a8e356daae28128310db872d61c01f8f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:38.046068) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:38.046328) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-08 02:43:38.076328) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 9 secs) (at 2016-05-08 02:43:38.079613) ========= /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.zU1sYfp61i/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.834nK67YRB/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 02:43:38 INFO - MultiFileLogger online at 20160508 02:43:38 in /builds/slave/test 02:43:38 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 02:43:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:43:38 INFO - {'append_to_log': False, 02:43:38 INFO - 'base_work_dir': '/builds/slave/test', 02:43:38 INFO - 'blob_upload_branch': 'Fx-Team', 02:43:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:43:38 INFO - 'branch': 'Fx-Team', 02:43:38 INFO - 'buildbot_json_path': 'buildprops.json', 02:43:38 INFO - 'config_files': ('talos/mac_config.py',), 02:43:38 INFO - 'default_actions': ('clobber', 02:43:38 INFO - 'read-buildbot-config', 02:43:38 INFO - 'download-and-extract', 02:43:38 INFO - 'populate-webroot', 02:43:38 INFO - 'create-virtualenv', 02:43:38 INFO - 'install', 02:43:38 INFO - 'run-tests'), 02:43:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:43:38 INFO - 'download_minidump_stackwalk': True, 02:43:38 INFO - 'download_symbols': 'ondemand', 02:43:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:43:38 INFO - 'tooltool.py': '/tools/tooltool.py', 02:43:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:43:38 INFO - '/tools/misc-python/virtualenv.py')}, 02:43:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:43:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:43:38 INFO - 'installer_path': 'installer.exe', 02:43:38 INFO - 'log_level': 'info', 02:43:38 INFO - 'log_name': 'talos', 02:43:38 INFO - 'log_to_console': True, 02:43:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:43:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:43:38 INFO - 'opt_config_files': (), 02:43:38 INFO - 'pip_index': False, 02:43:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:43:38 INFO - 'cmd': ('bash', 02:43:38 INFO - '-c', 02:43:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:43:38 INFO - 'enabled': True, 02:43:38 INFO - 'halt_on_failure': False, 02:43:38 INFO - 'name': 'check_screen_resolution'},), 02:43:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:43:38 INFO - 'cmd': ('bash', 02:43:38 INFO - '-c', 02:43:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:43:38 INFO - 'enabled': True, 02:43:38 INFO - 'halt_on_failure': False, 02:43:38 INFO - 'name': 'check_screen_resolution'},), 02:43:38 INFO - 'run_cmd_checks_enabled': True, 02:43:38 INFO - 'sps_profile': False, 02:43:38 INFO - 'sps_profile_interval': 0, 02:43:38 INFO - 'suite': 'dromaeojs', 02:43:38 INFO - 'system_bits': '32', 02:43:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:43:38 INFO - 'title': 't-yosemite-r7-0115', 02:43:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:43:38 INFO - 'use_talos_json': True, 02:43:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:43:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:43:38 INFO - 'work_dir': 'build'} 02:43:38 INFO - ##### 02:43:38 INFO - ##### Running clobber step. 02:43:38 INFO - ##### 02:43:38 INFO - Running pre-action listener: _resource_record_pre_action 02:43:38 INFO - Running main action method: clobber 02:43:38 INFO - rmtree: /builds/slave/test/build 02:43:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:43:40 INFO - Running post-action listener: _resource_record_post_action 02:43:40 INFO - ##### 02:43:40 INFO - ##### Finished clobber step (success) 02:43:40 INFO - ##### 02:43:40 INFO - ##### 02:43:40 INFO - ##### Running read-buildbot-config step. 02:43:40 INFO - ##### 02:43:40 INFO - Running pre-action listener: _resource_record_pre_action 02:43:40 INFO - Running main action method: read_buildbot_config 02:43:40 INFO - Using buildbot properties: 02:43:40 INFO - { 02:43:40 INFO - "project": "", 02:43:40 INFO - "product": "firefox", 02:43:40 INFO - "script_repo_revision": "production", 02:43:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:43:40 INFO - "repository": "", 02:43:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:43:40 INFO - "buildid": "20160508004657", 02:43:40 INFO - "pgo_build": "False", 02:43:40 INFO - "basedir": "/builds/slave/test", 02:43:40 INFO - "buildnumber": 4, 02:43:40 INFO - "slavename": "t-yosemite-r7-0115", 02:43:40 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:43:40 INFO - "platform": "yosemite_r7", 02:43:40 INFO - "scheduler": "tests-fx-team-macosx64-talos", 02:43:40 INFO - "branch": "fx-team", 02:43:40 INFO - "repo_path": "integration/fx-team", 02:43:40 INFO - "revision": "a3d59df2a8e356daae28128310db872d61c01f8f", 02:43:40 INFO - "stage_platform": "macosx64", 02:43:40 INFO - "builduid": "a2f52d406a154f0fb05c90c5a75d9e4b", 02:43:40 INFO - "slavebuilddir": "test" 02:43:40 INFO - } 02:43:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 02:43:40 INFO - Running post-action listener: _resource_record_post_action 02:43:40 INFO - ##### 02:43:40 INFO - ##### Finished read-buildbot-config step (success) 02:43:40 INFO - ##### 02:43:40 INFO - ##### 02:43:40 INFO - ##### Running download-and-extract step. 02:43:40 INFO - ##### 02:43:40 INFO - Running pre-action listener: _resource_record_pre_action 02:43:40 INFO - Running main action method: download_and_extract 02:43:40 INFO - mkdir: /builds/slave/test/build/tests 02:43:40 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')]} 02:43:40 INFO - https://queue.taskcluster.net/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:43:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:43:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:43:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/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 02:43:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/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 02:43:44 INFO - Downloaded 1199 bytes. 02:43:44 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 02:43:44 INFO - Using the following test package requirements: 02:43:44 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:43:44 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:43:44 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 02:43:44 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:43:44 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 02:43:44 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:43:44 INFO - u'jsshell-mac.zip'], 02:43:44 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:43:44 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 02:43:44 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:43:44 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:43:44 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 02:43:44 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:43:44 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 02:43:44 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:43:44 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 02:43:44 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:43:44 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 02:43:44 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:43:44 INFO - https://queue.taskcluster.net/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:43:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:43:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:43:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/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 02:43:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/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 02:43:45 INFO - Downloaded 18145037 bytes. 02:43:45 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'] 02:43:45 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 02:43:48 INFO - Return code: 0 02:43:48 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 02:43: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')]} 02:43:48 INFO - https://queue.taskcluster.net/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:43:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:43:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:43:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/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 02:43:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/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 02:43:50 INFO - Downloaded 18145037 bytes. 02:43:50 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'] 02:43:50 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 02:43:53 INFO - Return code: 0 02:43:53 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')]} 02:43:53 INFO - https://queue.taskcluster.net/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:43:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:43:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:43:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/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 02:43:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/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 02:43:54 INFO - Downloaded 10840152 bytes. 02:43:54 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'] 02:43:54 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 02:43:55 INFO - Return code: 0 02:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:43:55 INFO - https://queue.taskcluster.net/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:43:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:43:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:43:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 02:43:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RMY_V1yrQXOEKBblqD9A5Q/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 02:43:58 INFO - Downloaded 110314148 bytes. 02:43:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:43:58 INFO - mkdir: /builds/slave/test/properties 02:43:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:43:58 INFO - Writing to file /builds/slave/test/properties/build_url 02:43:58 INFO - Contents: 02:43:58 INFO - build_url:https://queue.taskcluster.net/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:43:59 INFO - Running post-action listener: _resource_record_post_action 02:43:59 INFO - Running post-action listener: set_extra_try_arguments 02:43:59 INFO - ##### 02:43:59 INFO - ##### Finished download-and-extract step (success) 02:43:59 INFO - ##### 02:43:59 INFO - ##### 02:43:59 INFO - ##### Running populate-webroot step. 02:43:59 INFO - ##### 02:43:59 INFO - Running pre-action listener: _resource_record_pre_action 02:43:59 INFO - Running main action method: populate_webroot 02:43:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:43:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:43:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:43:59 INFO - '--tppagecycles', 02:43:59 INFO - '7'], 02:43:59 INFO - 'tests': ['tsvgm']}}, 02:43:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:43:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:43:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:43:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:43:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:43:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:43:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:43:59 INFO - 'talos_options': ['--disable-e10s'], 02:43:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:43:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:43:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:43:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:43:59 INFO - 'talos_options': ['--disable-e10s'], 02:43:59 INFO - 'tests': ['damp', 'tps']}, 02:43:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:43:59 INFO - 'tests': ['damp', 'tps']}, 02:43:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:43:59 INFO - 'tests': ['dromaeo_dom']}, 02:43:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:43:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:43:59 INFO - 'tests': ['a11yr', 02:43:59 INFO - 'ts_paint', 02:43:59 INFO - 'tpaint', 02:43:59 INFO - 'sessionrestore', 02:43:59 INFO - 'sessionrestore_no_auto_restore', 02:43:59 INFO - 'tabpaint']}, 02:43:59 INFO - 'other-e10s': {'tests': ['a11yr', 02:43:59 INFO - 'ts_paint', 02:43:59 INFO - 'tpaint', 02:43:59 INFO - 'sessionrestore', 02:43:59 INFO - 'sessionrestore_no_auto_restore', 02:43:59 INFO - 'tabpaint']}, 02:43:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:43:59 INFO - 'ts_paint', 02:43:59 INFO - 'tpaint', 02:43:59 INFO - 'sessionrestore', 02:43:59 INFO - 'sessionrestore_no_auto_restore', 02:43:59 INFO - 'tabpaint']}, 02:43:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:43:59 INFO - 'ts_paint', 02:43:59 INFO - 'tpaint', 02:43:59 INFO - 'sessionrestore', 02:43:59 INFO - 'sessionrestore_no_auto_restore', 02:43:59 INFO - 'tabpaint']}, 02:43:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:43:59 INFO - 'tests': ['a11yr', 02:43:59 INFO - 'ts_paint', 02:43:59 INFO - 'tpaint', 02:43:59 INFO - 'sessionrestore', 02:43:59 INFO - 'sessionrestore_no_auto_restore', 02:43:59 INFO - 'tabpaint']}, 02:43:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:43:59 INFO - 'tests': ['a11yr', 02:43:59 INFO - 'ts_paint', 02:43:59 INFO - 'tpaint', 02:43:59 INFO - 'sessionrestore', 02:43:59 INFO - 'sessionrestore_no_auto_restore', 02:43:59 INFO - 'tabpaint']}, 02:43:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:43:59 INFO - 'tests': ['tsvgx', 02:43:59 INFO - 'tsvgr_opacity', 02:43:59 INFO - 'tart', 02:43:59 INFO - 'tscrollx', 02:43:59 INFO - 'cart']}, 02:43:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:43:59 INFO - 'tsvgr_opacity', 02:43:59 INFO - 'tart', 02:43:59 INFO - 'tscrollx', 02:43:59 INFO - 'cart']}, 02:43:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:43:59 INFO - 'talos_options': ['--disable-e10s'], 02:43:59 INFO - 'tests': ['tp5o']}, 02:43:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:43:59 INFO - 'tests': ['tp5o']}, 02:43:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:43:59 INFO - 'talos_options': ['--disable-e10s', 02:43:59 INFO - '--xperf_path', 02:43:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:43:59 INFO - 'tests': ['tp5n']}, 02:43:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:43:59 INFO - 'talos_options': ['--xperf_path', 02:43:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:43:59 INFO - 'tests': ['tp5n']}}, 02:43:59 INFO - 'talos.zip': {'path': '', 02:43:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:43:59 INFO - Running post-action listener: _resource_record_post_action 02:43:59 INFO - ##### 02:43:59 INFO - ##### Finished populate-webroot step (success) 02:43:59 INFO - ##### 02:43:59 INFO - ##### 02:43:59 INFO - ##### Running create-virtualenv step. 02:43:59 INFO - ##### 02:43:59 INFO - Running pre-action listener: _resource_record_pre_action 02:43:59 INFO - Running main action method: create_virtualenv 02:43:59 INFO - Creating virtualenv /builds/slave/test/build/venv 02:43:59 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 02:43:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:43:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:43:59 INFO - Using real prefix '/tools/python27' 02:43:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:00 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:02 INFO - Installing pip.................done. 02:44:02 INFO - Return code: 0 02:44:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:44:02 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')]} 02:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:02 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')]} 02:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:02 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 0x10cad4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cada570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9495120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c978d30>, '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 0x10c79cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c96fb20>, '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.zU1sYfp61i/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.834nK67YRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 02:44:02 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 02:44:02 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 02:44:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zU1sYfp61i/Render', 02:44:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:02 INFO - 'HOME': '/Users/cltbld', 02:44:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:02 INFO - 'LOGNAME': 'cltbld', 02:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:02 INFO - 'MOZ_NO_REMOTE': '1', 02:44:02 INFO - 'NO_EM_RESTART': '1', 02:44:02 INFO - 'PAGER': '/bin/cat', 02:44:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:02 INFO - 'PWD': '/builds/slave/test', 02:44:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:02 INFO - 'SHELL': '/bin/bash', 02:44:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.834nK67YRB/Listeners', 02:44:02 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 02:44:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:02 INFO - 'USER': 'cltbld', 02:44:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:02 INFO - 'XPC_FLAGS': '0x0', 02:44:02 INFO - 'XPC_SERVICE_NAME': '0', 02:44:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:03 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 02:44:03 INFO - Cleaning up... 02:44:03 INFO - Return code: 0 02:44:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:44:03 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')]} 02:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:03 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')]} 02:44:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:03 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 0x10cad4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cada570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9495120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c978d30>, '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 0x10c79cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c96fb20>, '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.zU1sYfp61i/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.834nK67YRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 02:44:03 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 02:44:03 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 02:44:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zU1sYfp61i/Render', 02:44:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:03 INFO - 'HOME': '/Users/cltbld', 02:44:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:03 INFO - 'LOGNAME': 'cltbld', 02:44:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:03 INFO - 'MOZ_NO_REMOTE': '1', 02:44:03 INFO - 'NO_EM_RESTART': '1', 02:44:03 INFO - 'PAGER': '/bin/cat', 02:44:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:03 INFO - 'PWD': '/builds/slave/test', 02:44:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:03 INFO - 'SHELL': '/bin/bash', 02:44:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.834nK67YRB/Listeners', 02:44:03 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 02:44:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:03 INFO - 'USER': 'cltbld', 02:44:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:03 INFO - 'XPC_FLAGS': '0x0', 02:44:03 INFO - 'XPC_SERVICE_NAME': '0', 02:44:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:03 INFO - Downloading/unpacking psutil>=0.7.1 02:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:44:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:06 INFO - Installing collected packages: psutil 02:44:06 INFO - Running setup.py install for psutil 02:44:06 INFO - building 'psutil._psutil_osx' extension 02:44:06 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 02:44:07 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 02:44:07 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 02:44:07 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 02:44:07 INFO - building 'psutil._psutil_posix' extension 02:44:07 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 02:44:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:44:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:44:07 INFO - ^ 02:44:07 INFO - 1 warning generated. 02:44:07 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 02:44:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:07 INFO - Successfully installed psutil 02:44:07 INFO - Cleaning up... 02:44:07 INFO - Return code: 0 02:44:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:44:07 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')]} 02:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:07 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')]} 02:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:07 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 0x10cad4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cada570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9495120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c978d30>, '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 0x10c79cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c96fb20>, '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.zU1sYfp61i/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.834nK67YRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 02:44:07 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 02:44:07 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 02:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zU1sYfp61i/Render', 02:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:07 INFO - 'HOME': '/Users/cltbld', 02:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:07 INFO - 'LOGNAME': 'cltbld', 02:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:07 INFO - 'MOZ_NO_REMOTE': '1', 02:44:07 INFO - 'NO_EM_RESTART': '1', 02:44:07 INFO - 'PAGER': '/bin/cat', 02:44:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:07 INFO - 'PWD': '/builds/slave/test', 02:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:07 INFO - 'SHELL': '/bin/bash', 02:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.834nK67YRB/Listeners', 02:44:07 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 02:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:07 INFO - 'USER': 'cltbld', 02:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:07 INFO - 'XPC_FLAGS': '0x0', 02:44:07 INFO - 'XPC_SERVICE_NAME': '0', 02:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:44: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 02:44: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 02:44: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 02:44: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 02:44: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 02:44: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 02:44:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:44:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:44:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:44:10 INFO - Installing collected packages: mozsystemmonitor 02:44:10 INFO - Running setup.py install for mozsystemmonitor 02:44:10 INFO - Successfully installed mozsystemmonitor 02:44:10 INFO - Cleaning up... 02:44:10 INFO - Return code: 0 02:44:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cad4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cada570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9495120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c978d30>, '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 0x10c79cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c96fb20>, '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.zU1sYfp61i/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.834nK67YRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 02:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:44:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zU1sYfp61i/Render', 02:44:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:10 INFO - 'HOME': '/Users/cltbld', 02:44:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:10 INFO - 'LOGNAME': 'cltbld', 02:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:10 INFO - 'MOZ_NO_REMOTE': '1', 02:44:10 INFO - 'NO_EM_RESTART': '1', 02:44:10 INFO - 'PAGER': '/bin/cat', 02:44:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:10 INFO - 'PWD': '/builds/slave/test', 02:44:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:10 INFO - 'SHELL': '/bin/bash', 02:44:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.834nK67YRB/Listeners', 02:44:10 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 02:44:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:10 INFO - 'USER': 'cltbld', 02:44:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:10 INFO - 'XPC_FLAGS': '0x0', 02:44:10 INFO - 'XPC_SERVICE_NAME': '0', 02:44:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:10 INFO - Downloading/unpacking blobuploader==1.2.4 02:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:13 INFO - Downloading blobuploader-1.2.4.tar.gz 02:44:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:44:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:44:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:44:14 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 02:44:14 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 02:44:14 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 02:44:14 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 02:44:14 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 02:44:14 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 02:44:14 INFO - Downloading docopt-0.6.1.tar.gz 02:44:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:44:14 INFO - Installing collected packages: blobuploader, requests, docopt 02:44:14 INFO - Running setup.py install for blobuploader 02:44:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:44:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:44:14 INFO - Running setup.py install for requests 02:44:15 INFO - Running setup.py install for docopt 02:44:15 INFO - Successfully installed blobuploader requests docopt 02:44:15 INFO - Cleaning up... 02:44:15 INFO - Return code: 0 02:44:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:15 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')]} 02:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:15 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')]} 02:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:15 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 0x10cad4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cada570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9495120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c978d30>, '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 0x10c79cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c96fb20>, '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.zU1sYfp61i/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.834nK67YRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 02:44:15 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 02:44:15 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 02:44:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zU1sYfp61i/Render', 02:44:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:15 INFO - 'HOME': '/Users/cltbld', 02:44:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:15 INFO - 'LOGNAME': 'cltbld', 02:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:15 INFO - 'MOZ_NO_REMOTE': '1', 02:44:15 INFO - 'NO_EM_RESTART': '1', 02:44:15 INFO - 'PAGER': '/bin/cat', 02:44:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:15 INFO - 'PWD': '/builds/slave/test', 02:44:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:15 INFO - 'SHELL': '/bin/bash', 02:44:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.834nK67YRB/Listeners', 02:44:15 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 02:44:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:15 INFO - 'USER': 'cltbld', 02:44:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:15 INFO - 'XPC_FLAGS': '0x0', 02:44:15 INFO - 'XPC_SERVICE_NAME': '0', 02:44:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:15 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-pkB8O5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:15 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ozAKHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:16 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-2hmL1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:16 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-OigSkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:16 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-MtRrl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:16 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-uPCm2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:16 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-RcX0rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:16 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-WZz_V7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:16 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-rKa8zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:16 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-gPeBfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:16 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-P3c2aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:16 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-IPLDB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:17 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-zF01dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:17 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-6YAWcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:17 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-SbOxpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:17 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-vIAnNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:17 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-hxPaw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:44:17 INFO - Running setup.py install for manifestparser 02:44:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:44:17 INFO - Running setup.py install for mozcrash 02:44:17 INFO - Running setup.py install for mozdebug 02:44:17 INFO - Running setup.py install for mozdevice 02:44:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:44:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:44:18 INFO - Running setup.py install for mozfile 02:44:18 INFO - Running setup.py install for mozhttpd 02:44:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:44:18 INFO - Running setup.py install for mozinfo 02:44:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:44:18 INFO - Running setup.py install for mozInstall 02:44:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:44:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:44:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:44:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:44:18 INFO - Running setup.py install for mozleak 02:44:18 INFO - Running setup.py install for mozlog 02:44:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:44:19 INFO - Running setup.py install for moznetwork 02:44:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:44:19 INFO - Running setup.py install for mozprocess 02:44:19 INFO - Running setup.py install for mozprofile 02:44:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:44:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:44:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:44:19 INFO - Running setup.py install for mozrunner 02:44:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:44:19 INFO - Running setup.py install for mozscreenshot 02:44:19 INFO - Running setup.py install for moztest 02:44:19 INFO - Running setup.py install for mozversion 02:44:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:44:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:44:20 INFO - Cleaning up... 02:44:20 INFO - Return code: 0 02:44:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:20 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')]} 02:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:20 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')]} 02:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:20 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 0x10cad4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cada570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9495120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c978d30>, '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 0x10c79cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c96fb20>, '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.zU1sYfp61i/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.834nK67YRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 02:44:20 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 02:44:20 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 02:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zU1sYfp61i/Render', 02:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:20 INFO - 'HOME': '/Users/cltbld', 02:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:20 INFO - 'LOGNAME': 'cltbld', 02:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:20 INFO - 'MOZ_NO_REMOTE': '1', 02:44:20 INFO - 'NO_EM_RESTART': '1', 02:44:20 INFO - 'PAGER': '/bin/cat', 02:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:20 INFO - 'PWD': '/builds/slave/test', 02:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:20 INFO - 'SHELL': '/bin/bash', 02:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.834nK67YRB/Listeners', 02:44:20 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 02:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:20 INFO - 'USER': 'cltbld', 02:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:20 INFO - 'XPC_FLAGS': '0x0', 02:44:20 INFO - 'XPC_SERVICE_NAME': '0', 02:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:20 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-F4SG3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:20 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)) 02:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:20 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-vBS0nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:20 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)) 02:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:20 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-YLx8QB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:20 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)) 02:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:20 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-pGyLe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:20 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)) 02:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:20 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-1CUzKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:20 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)) 02:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:20 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-AsAJ89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:21 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)) 02:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:21 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-caxf5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:21 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)) 02:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:21 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-6VHGku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:21 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)) 02:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:21 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Z9rzjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:21 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)) 02:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:21 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-LngHVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:21 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-2WQUA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:21 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)) 02:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:21 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-uYiVIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:21 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)) 02:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:21 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-_dKeo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:21 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)) 02:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:21 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-XltU3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:21 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)) 02:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:21 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-fvRDdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:21 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)) 02:44:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:21 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-QnYIgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:22 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)) 02:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:22 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-nTKqsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:22 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)) 02:44:22 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)) 02:44:22 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)) 02:44:22 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)) 02:44:22 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)) 02:44:22 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)) 02:44:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:44: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 02:44: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 02:44: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 02:44: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 02:44: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 02:44: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 02:44:24 INFO - Downloading blessings-1.6.tar.gz 02:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:44:24 INFO - Installing collected packages: blessings 02:44:24 INFO - Running setup.py install for blessings 02:44:24 INFO - Successfully installed blessings 02:44:24 INFO - Cleaning up... 02:44:24 INFO - Return code: 0 02:44:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:44:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:25 INFO - Reading from file tmpfile_stdout 02:44:25 INFO - Current package versions: 02:44:25 INFO - blessings == 1.6 02:44:25 INFO - blobuploader == 1.2.4 02:44:25 INFO - docopt == 0.6.1 02:44:25 INFO - manifestparser == 1.1 02:44:25 INFO - mozInstall == 1.12 02:44:25 INFO - mozcrash == 0.17 02:44:25 INFO - mozdebug == 0.1 02:44:25 INFO - mozdevice == 0.48 02:44:25 INFO - mozfile == 1.2 02:44:25 INFO - mozhttpd == 0.7 02:44:25 INFO - mozinfo == 0.9 02:44:25 INFO - mozleak == 0.1 02:44:25 INFO - mozlog == 3.1 02:44:25 INFO - moznetwork == 0.27 02:44:25 INFO - mozprocess == 0.22 02:44:25 INFO - mozprofile == 0.28 02:44:25 INFO - mozrunner == 6.11 02:44:25 INFO - mozscreenshot == 0.1 02:44:25 INFO - mozsystemmonitor == 0.0 02:44:25 INFO - moztest == 0.7 02:44:25 INFO - mozversion == 1.4 02:44:25 INFO - psutil == 3.1.1 02:44:25 INFO - requests == 1.2.3 02:44:25 INFO - wsgiref == 0.1.2 02:44:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:25 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')]} 02:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:25 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')]} 02:44:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:25 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 0x10cad4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cada570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9495120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c978d30>, '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 0x10c79cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c96fb20>, '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.zU1sYfp61i/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.834nK67YRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 02:44:25 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 02:44:25 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 02:44:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zU1sYfp61i/Render', 02:44:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:25 INFO - 'HOME': '/Users/cltbld', 02:44:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:25 INFO - 'LOGNAME': 'cltbld', 02:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:25 INFO - 'MOZ_NO_REMOTE': '1', 02:44:25 INFO - 'NO_EM_RESTART': '1', 02:44:25 INFO - 'PAGER': '/bin/cat', 02:44:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:25 INFO - 'PWD': '/builds/slave/test', 02:44:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:25 INFO - 'SHELL': '/bin/bash', 02:44:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.834nK67YRB/Listeners', 02:44:25 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 02:44:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:25 INFO - 'USER': 'cltbld', 02:44:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:25 INFO - 'XPC_FLAGS': '0x0', 02:44:25 INFO - 'XPC_SERVICE_NAME': '0', 02:44:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:25 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)) 02:44:25 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)) 02:44:25 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)) 02:44:25 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)) 02:44:25 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)) 02:44:25 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)) 02:44:25 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)) 02:44:25 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)) 02:44:25 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)) 02:44:25 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)) 02:44:25 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)) 02:44:25 INFO - Cleaning up... 02:44:25 INFO - Return code: 0 02:44:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:44:25 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')]} 02:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:25 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')]} 02:44:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:25 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 0x10cad4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cada570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9495120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c978d30>, '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 0x10c79cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c96fb20>, '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.zU1sYfp61i/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.834nK67YRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 02:44:25 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 02:44:25 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 02:44:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zU1sYfp61i/Render', 02:44:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:25 INFO - 'HOME': '/Users/cltbld', 02:44:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:25 INFO - 'LOGNAME': 'cltbld', 02:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:25 INFO - 'MOZ_NO_REMOTE': '1', 02:44:25 INFO - 'NO_EM_RESTART': '1', 02:44:25 INFO - 'PAGER': '/bin/cat', 02:44:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:25 INFO - 'PWD': '/builds/slave/test', 02:44:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:25 INFO - 'SHELL': '/bin/bash', 02:44:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.834nK67YRB/Listeners', 02:44:25 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 02:44:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:25 INFO - 'USER': 'cltbld', 02:44:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:25 INFO - 'XPC_FLAGS': '0x0', 02:44:25 INFO - 'XPC_SERVICE_NAME': '0', 02:44:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:25 INFO - Downloading/unpacking jsonschema 02:44: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 02:44: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 02:44: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 02:44: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 02:44: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 02:44: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 02:44:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:44:28 INFO - Downloading/unpacking functools32 (from jsonschema) 02:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:28 INFO - Downloading functools32-3.2.3-2.tar.gz 02:44:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:44:28 INFO - warning: no files found matching '*.txt' 02:44:28 INFO - no previously-included directories found matching 'build' 02:44:28 INFO - no previously-included directories found matching 'dist' 02:44:28 INFO - no previously-included directories found matching '.git*' 02:44:28 INFO - Installing collected packages: jsonschema, functools32 02:44:28 INFO - Running setup.py install for functools32 02:44:28 INFO - warning: no files found matching '*.txt' 02:44:28 INFO - no previously-included directories found matching 'build' 02:44:28 INFO - no previously-included directories found matching 'dist' 02:44:28 INFO - no previously-included directories found matching '.git*' 02:44:28 INFO - Successfully installed jsonschema functools32 02:44:28 INFO - Cleaning up... 02:44:28 INFO - Return code: 0 02:44:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cad4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cada570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefa9495120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c978d30>, '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 0x10c79cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c96fb20>, '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.zU1sYfp61i/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.834nK67YRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 02:44:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:44:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:44:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zU1sYfp61i/Render', 02:44:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:28 INFO - 'HOME': '/Users/cltbld', 02:44:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:28 INFO - 'LOGNAME': 'cltbld', 02:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:28 INFO - 'MOZ_NO_REMOTE': '1', 02:44:28 INFO - 'NO_EM_RESTART': '1', 02:44:28 INFO - 'PAGER': '/bin/cat', 02:44:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:28 INFO - 'PWD': '/builds/slave/test', 02:44:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:28 INFO - 'SHELL': '/bin/bash', 02:44:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.834nK67YRB/Listeners', 02:44:28 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 02:44:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:28 INFO - 'USER': 'cltbld', 02:44:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:28 INFO - 'XPC_FLAGS': '0x0', 02:44:28 INFO - 'XPC_SERVICE_NAME': '0', 02:44:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:29 INFO - Downloading/unpacking flake8 02:44:29 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 02:44:29 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 02:44:29 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 02:44:29 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 02:44:29 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 02:44:29 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 02:44:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:44:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:44:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:44:32 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 02:44:32 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 02:44:32 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 02:44:32 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 02:44:32 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 02:44:32 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 02:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:44:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:44:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:44:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:44:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:44:32 INFO - no previously-included directories found matching 'docs/_build' 02:44:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:44:32 INFO - Running setup.py install for pep8 02:44:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:44:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:44:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:44:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:44:33 INFO - no previously-included directories found matching 'docs/_build' 02:44:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:44:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:44:33 INFO - Cleaning up... 02:44:33 INFO - Return code: 0 02:44:33 INFO - Running post-action listener: _resource_record_post_action 02:44:33 INFO - Running post-action listener: _start_resource_monitoring 02:44:33 INFO - Starting resource monitoring. 02:44:33 INFO - ##### 02:44:33 INFO - ##### Finished create-virtualenv step (success) 02:44:33 INFO - ##### 02:44:33 INFO - ##### 02:44:33 INFO - ##### Running install step. 02:44:33 INFO - ##### 02:44:33 INFO - Running pre-action listener: _resource_record_pre_action 02:44:33 INFO - Running main action method: install 02:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:33 INFO - Reading from file tmpfile_stdout 02:44:33 INFO - Detecting whether we're running mozinstall >=1.0... 02:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:44:33 INFO - Reading from file tmpfile_stdout 02:44:33 INFO - Output received: 02:44:33 INFO - Usage: mozinstall [options] installer 02:44:33 INFO - Options: 02:44:33 INFO - -h, --help show this help message and exit 02:44:33 INFO - -d DEST, --destination=DEST 02:44:33 INFO - Directory to install application into. [default: 02:44:33 INFO - "/builds/slave/test"] 02:44:33 INFO - --app=APP Application being installed. [default: firefox] 02:44:33 INFO - mkdir: /builds/slave/test/build/application 02:44:33 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'] 02:44:33 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 02:45:06 INFO - Reading from file tmpfile_stdout 02:45:06 INFO - Output received: 02:45:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:45:06 INFO - Running post-action listener: _resource_record_post_action 02:45:06 INFO - ##### 02:45:06 INFO - ##### Finished install step (success) 02:45:06 INFO - ##### 02:45:06 INFO - ##### 02:45:06 INFO - ##### Running run-tests step. 02:45:06 INFO - ##### 02:45:06 INFO - Running pre-action listener: _resource_record_pre_action 02:45:06 INFO - Running main action method: run_tests 02:45:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:45:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:45:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:45:06 INFO - 2016-05-08 02:45:06.911 screenresolution[1650:7776] starting screenresolution argv=screenresolution get 02:45:06 INFO - 2016-05-08 02:45:06.914 screenresolution[1650:7776] Display 0: 1600x1200x32@60 02:45:06 INFO - 2016-05-08 02:45:06.920 screenresolution[1651:7778] starting screenresolution argv=screenresolution list 02:45:06 INFO - Available Modes on Display 0 02:45:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:45:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:45:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:45:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:45:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:45:07 INFO - Intel Iris: 02:45:07 INFO - Chipset Model: Intel Iris 02:45:07 INFO - Type: GPU 02:45:07 INFO - Bus: Built-In 02:45:07 INFO - VRAM (Dynamic, Max): 1536 MB 02:45:07 INFO - Vendor: Intel (0x8086) 02:45:07 INFO - Device ID: 0x0a2e 02:45:07 INFO - Revision ID: 0x0009 02:45:07 INFO - Displays: 02:45:07 INFO - Display: 02:45:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:45:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:45:07 INFO - Display Serial Number: 1600x1200 60 02:45:07 INFO - Main Display: Yes 02:45:07 INFO - Mirror: Off 02:45:07 INFO - Online: Yes 02:45:07 INFO - Rotation: Supported 02:45:07 INFO - Return code: 0 02:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:07 INFO - Python 2.7.3 02:45:07 INFO - Return code: 0 02:45:07 INFO - grabbing minidump binary from tooltool 02:45:07 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')]} 02:45:07 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c978d30>, '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 0x10c79cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c96fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:07 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 02:45:07 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 02:45:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:07 INFO - Return code: 0 02:45:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:45:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:45:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:45:08 INFO - Return code: 0 02:45:08 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/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--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 02:45:08 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/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0115 --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 02:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zU1sYfp61i/Render', 02:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:08 INFO - 'HOME': '/Users/cltbld', 02:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:08 INFO - 'LOGNAME': 'cltbld', 02:45:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:08 INFO - 'MOZ_NO_REMOTE': '1', 02:45:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:08 INFO - 'NO_EM_RESTART': '1', 02:45:08 INFO - 'PAGER': '/bin/cat', 02:45:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:08 INFO - 'PWD': '/builds/slave/test', 02:45:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:08 INFO - 'SHELL': '/bin/bash', 02:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.834nK67YRB/Listeners', 02:45:08 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 02:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:08 INFO - 'USER': 'cltbld', 02:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:08 INFO - 'XPC_FLAGS': '0x0', 02:45:08 INFO - 'XPC_SERVICE_NAME': '0', 02:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:08 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/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--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 02:45:08 INFO - mozversion application_buildid: 20160508004657 02:45:08 INFO - mozversion application_changeset: a3d59df2a8e356daae28128310db872d61c01f8f 02:45:08 INFO - mozversion application_display_name: Nightly 02:45:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:08 INFO - mozversion application_name: Firefox 02:45:08 INFO - mozversion application_remotingname: firefox 02:45:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:45:08 INFO - mozversion application_vendor: Mozilla 02:45:08 INFO - mozversion application_version: 49.0a1 02:45:08 INFO - mozversion platform_buildid: 20160508004657 02:45:08 INFO - mozversion platform_changeset: a3d59df2a8e356daae28128310db872d61c01f8f 02:45:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:45:08 INFO - mozversion platform_version: 49.0a1 02:45:08 INFO - using testdate: 1462700708 02:45:08 INFO - actual date: 1462700708 02:45:08 INFO - starting webserver on 'localhost:49280' 02:45:08 INFO - SUITE-START | Running 2 tests 02:45:08 INFO - TEST-START | dromaeo_css 02:45:08 INFO - operating with platform_type : mac_ 02:45:08 INFO - Initialising browser for dromaeo_css test... 02:45:08 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpKbwnbH/profile http://localhost:49280/getInfo.html) 02:45:11 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 02:45:11 INFO - PROCESS | 1669 | colorDepth:24 02:45:11 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 02:45:11 INFO - PROCESS | 1669 | __metrics 02:45:11 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:45:11 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:45:11 INFO - TEST-INFO | 1669: exit 0 02:45:11 INFO - Browser initialized. 02:45:11 INFO - Running cycle 1/1 for dromaeo_css test... 02:45:11 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpKbwnbH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:23 INFO - PROCESS | 1671 | RSS: Main: 238882816 02:45:23 INFO - PROCESS | 1671 | 02:46:15 INFO - PROCESS | 1671 | 1462700775122 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] 02:48:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 02:48:26 INFO - PROCESS | 1671 | RSS: Main: 323825664 02:48:26 INFO - PROCESS | 1671 | 02:51:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 02:51:30 INFO - PROCESS | 1671 | RSS: Main: 376676352 02:51:30 INFO - PROCESS | 1671 | 02:54:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 02:54:33 INFO - PROCESS | 1671 | RSS: Main: 371372032 02:54:33 INFO - PROCESS | 1671 | 02:57:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 02:57:36 INFO - PROCESS | 1671 | RSS: Main: 370814976 02:57:36 INFO - PROCESS | 1671 | 03:00:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 03:00:40 INFO - PROCESS | 1671 | RSS: Main: 344072192 03:00:40 INFO - PROCESS | 1671 | 03:03:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 03:03:43 INFO - PROCESS | 1671 | RSS: Main: 377499648 03:03:43 INFO - PROCESS | 1671 | 03:03:43 INFO - PROCESS | 1671 | __start_tp_report 03:03:43 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 03:03:43 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 03:03:43 INFO - PROCESS | 1671 | |i|pagename|runs| 03:03:43 INFO - PROCESS | 1671 | |0;dojo.html;2858.58550510176;3429.794212347261;3461.6365281645444;3465.237647717502;3504.754667173297;5572.9411411831725;5581.916271255935;5633.070543360341;5648.943510564898;5732.340780810203;5654.321481422225;5712.485876271135;5722.713864306778;5752.539796816255;5769.884602307951;5162.5611822995;5202.557782588485;5221.425643179247;5244.134717771563;5272.340957380327;5276.604254680895;5293.338332708411;5295.126304159809;5297.205419187114;5299.019681358942;5132.409772876111;5189.584833213353;5199.298094757197;5205;5274.971380580789;5286.154215325549;5295.391030031557;5303.018941495836;5306.548943339821;5379.542738867201;5091.8981620367385;5103.183490641517;5135.8202462914;5221.6083793715625;5227.424983251839;5508.3114610673665;5537.307836520395;5593.608447408683;5603.2715746953045;5618;4516.616087632555;5206.583473322163;5257.842264732026;6240.438360547572;6240.5319601030105;6140.074827791158;6181.227346581677;6265.87468250637;6272.529560282951;6279.780207692709;5826.359100498942;5850.765969361178;5912.822615321505;5933.58464907461;5963.344032156459;6223.657698826518;6242.5942313749465;6255.40573645503;6265.4361107500545;6276.184096067574;6024.457798798129;6093.116498107838;6118.051701986199;6147.924113280158;6187.381261873777;6246.531510136758;6258.217722784652;6267.96866015667;6288.842778930563;6289.96855015722;6176.382361763787;6222.844428889314;6247.125402443661;6253.155823963798;6272.717727702265;6139.815805525842;6142.2839182294965;6146.8935591593045;6170.598911070766;6269.965455699758;4836.177849765549;4882.95117048825;4901.387326584177;4905.533974272438;4948.752562371867;4362;4398.054418300081;4410.184115938561;4413.13943780966;4474.194644963938;5632.887342253132;5685.829425117253;5686.317641883001;5744.540436765049;5745.9712701437065;6265.561410701298;6272.059191121369;6272.6236425814595;6288.937110628926;6298.433141017239;5717.342505611825;5736.082226843685;5756.251687280626;5757.251557297524;5804.970975145097;5259;5342.839714808562;5351.759170837322;5368.4631536846;5408.540274076748;6301.6534090625455;6336.398042185985;6343.492520598249;6373.5857169283845;6383.776567820104;6126.5711400203345;6241.3134555197985;6249;6249.218847644044;6251.53113516479;6169;6236.563440559299;6279.591826531444;6338.492920566251;6449.322821103717;6171.259448866164;6185.536084793748;6193.938060619336;6200.782972595937;6217.626942383471;6136.1821086261625;6233.189685340877;6258.718357673824;6273.686315684106;6280.937190628218;6034.456898879122;6084.939150608614;6100.786541944698;6110.16388879229;6144.262688477412;6103.428585615474;6112.419320164578;6124.142620033288;6124.265088189446;6141.88493709207;5754.9424505754405;5825.650460972355;5956.493698035715;5977.193078934288;5979.162917191509;5130.7178105203075;5941.673207973603;6012.518998480198;6084.756609735561;6113.871604717183;4963.255511673278;5980;6008.819735407945;6009.759609615567;6019.337872833898 03:03:43 INFO - PROCESS | 1671 | |1;ext.html;497.0903247057882;500.7070863544826;512.825639282644;514.6989011428315;547.9178123281508;29479.263018424524;30647;41791;43077.78461107698;43092;43809.7809510952;44216.77891610545;44240;44376.556234437645;44542.10915781691;8826.073262307462;9820.754481137976;10004.549795259194;10018.849717254247;10038.949805250964;8852.291816654684;11792.528298868036;12032.699182520419;12056.397180141003;12218.93890530546;12011.699707507294;12065.3967301635;12077;12088.21426607272;12112.757744845097;8847.469151850844;11750.353730544817;11930.701732456671;11988.100892433102;12060.095492838029;9836.90163098367;9950.552225149886;11857.288562686266;11874;11897;40022.799886000386;40406.19187616231;40450;40465;40489.79755101206;3045.8629361678554;3047.024952015356;3361.680640339164;3541.592716837571;3640.6359364063646;28497.715022849712;28692.41410770682;28832.423351533063;29535.113946581638;29651.258718531994;2687.1401151631485;2736.179146256115;2789.4839454700946;2793.7625301849366;2834.702356252605;490279;490845;491414.99999999645;491489;491799;60192.09711854326;60776;60884.39115608876;60892.695536522035;60907.69546152241;64251.678741605996;64375.67812160909;64463;64507.35492645107;64515.709685806956;16862.57843553921;17110.315587376615;17126.14369281531;17148.74276885823;17186;10261.74345641365;12348.506059757688;12965.546205882749;13165;13169.736605268032;16150.838491615168;16193.514194574182;16212.918935405247;16263.129740080043;16293;7389.889151662729;7391.519551229153;7426;7439.665215065335;7504.474686771982;919.3748251189255;1011.3426272922512;1038.8649475568127;1076.7455913860265;1104.5802595014004;28703.56944645832;29127.7087229125;29224;29821;29919.85040074829;1056.8849863394194;1073.2326386633595;1108.3405373802575;1157.571698471554;1215.4834195466892;15116.470923517623;15172.772408413883;15190.392384304725;15477.922610386877;15506.922465387826;631397;631851;636481;636560;637577;60004;60031.699841500515;60054.69972650109;60199.39800602025;60385.39614603798;1314.59247633234;1453.7165252775608;1480.8667219950257;1495.1477657735204;1514.167208035554;30082.68263664655;30142.24722687703;30298.091057268317;30545.93089241866;30813.69186308198;49972;50649.49350506448;50708.23937641012;50743.74628126836;50837;483371;500576;501109;502505;502718;32899;38437.615623843405;38478.6152138475;38747.225055498166;39189.41215881707;44983;45230;45349.319760204286;45788.54211457976;45876.31185532286;37672.811635941645;37942;38049.61950380571;38049.80975095107;38465;31545.738170472923;34683.30633387268;37229.441558377184;37490.625093749804;37722.81138594289;36439.81780091082;36952.706655266935;37142.814285929475;37530.812345938095;37813.43279850749;32357.514637280932;32380.35239295154;32783.50824737678;32835.50746738753;32993;19093.809061909204;24219.75780242175;24757.381065473506;24877.751222487543;25033.624495632215 03:03:43 INFO - PROCESS | 1671 | |2;jquery.html;392.5387669488351;397.2353220051401;397.3384315115333;398.23340070364543;419.7145940760284;14676.559703208894;20220.89889550552;21073.262435814748;21272.829994350323;21308.46728831778;15625.609359765984;17618.647627047485;19690.606187876234;20247.190112395485;20645;5742.598018138732;6140.35526269742;6152.538559608025;6207.906881396782;6234.096056071867;5794.478496935275;6122.081687746829;6176.289726681444;6181.690915454232;6187.752489900398;6104.816855494342;6142.662153581551;6156.6613836238985;6159.168512250833;6162.5069994400565;5999;6062.696865156725;6140.1096840958035;6143.508519318466;6160.599561028519;5331.093714068617;5333.973330133324;5342.5993050521365;5350.3312085989255;5383.111786555253;11197;14044.859551404457;23681.76318236813;24144;25649;2142.582196471689;2155.7736437673975;2229.6544035674497;2300.5858945389823;2414.9758502414925;9761.316707830454;10834.566617335297;11871;13312.667183320398;15339.772818174519;1631.6002579368012;1683.6632673465233;1702.131912724507;1733.0901276829638;1784.3843873863495;121658.39170804177;124142.75857241402;128723;130626;130908.3454582721;11544.826827597593;11596.536138554533;11707.882921170849;11714.121440891968;11860.110491713156;12550.874491255154;15191.316390762442;15217.543473695807;15226.923865380602;15232.390704371923;7440.73957411488;7499.587522686195;8651.91348086511;8795.428297160664;8818.11818818113;8640.524771137647;8644.438111522732;8658.956705216433;8743.650253989896;8763.780905477286;4588.220002599566;4608.953910460919;4627.537246275346;4645.74448405341;4704.717716936994;1685.4101064627282;1902.3627084926427;1938.0503553258802;1960.431474872271;1970.7930667280016;879.7668617816283;894.2383504496132;923.0077666508396;1004.5680357446369;1044.472541366605;18623.068846557617;19441.902790485958;20619;20676.896615517126;20816.375508734764;954.6992697300328;958.9664361747306;989.5497548615363;1004.1364426593124;1020.979020979021;8737.56312184401;10568.52441640128;10863.45682715861;10873.510692018877;10880.510377033053;616567;624607;637983;640978;641470;130767;132774;135045.3247733755;135545.322273388;142009;1007.09361574582;1014.7868947520956;1024.3393011507535;1036.160709825044;1038.055369613648;14283.214423206824;17413.386179631223;17792.288308467516;18024.36914707979;18114.09429528492;52171.73914130405;53302;54056.72971635117;56057.197140142016;56771.71614141903;79670.20329796628;81204;81489.18510814816;83137;83180.16819831966;6890.724371025103;6975.720971161097;7372.336489715952;7418.146913105062;8142.918570814216;10562.032451664785;12121.575744848888;12470;12665.290185825164;12731.427085781348;10403.84394234072;10672;10771.246012779031;10895.727606809893;11022.944885275523;10482.475876206312;10572.524236409534;10595.523201455799;10772.407517586607;10776.407297598707;10840;11030.779384412428;11067.667969960914;11275.15436342294;11326.263792853439;7251.41988640918;7282.162551306667;7309.451791115794;7465.776026719207;7490.325870671666;9754.75613109678;10956.178286628376;11034.724131896766;11086.168537359892;11105.444727763417 03:03:43 INFO - PROCESS | 1671 | |3;mootools.html;1404.3118871752845;1587.8174009988852;1611.9597010074747;1826.9451916442504;1955.550223448606;7696.8075798105;8525.573721313933;8778.122187781219;8796.95601521993;8882.333824963122;8566.05773364931;8744.256738177246;8753.474791512514;8760.474371537697;8785;2169.2190811307914;2227.6324406472913;2400.219928523228;2461.975380246197;2490.6139548369943;2089.8119169274764;2520.5463016656995;2524.9873750631314;2526.39366552028;2527.6208568714746;2448.6449464827588;2466.938326541842;2494.3639371960126;2495.5757521221344;2500.1499490173337;2143.3784202581232;2370.6918100646976;2439.2804122783823;2440.5606990741658;2483.416397146669;2458.606622940321;2485.6395822605623;2486.9378265543323;2494.0896572751;2495.3512086857368;8603.526806025666;8680.609372578248;8690.652373905035;8691.043985161627;8712.346574006911;5206.167013277857;5365.3293338332705;5401.756920938567;5407.621466497347;5410.377806552257;2878.481873262812;3397.5922889253447;3638.9272214555563;3644.908877278063;3657.8719744808805;1035.321864178964;1046.4140081554353;1046.9738256543646;1047.2250534604414;1055.0662663542744;11513.942430287794;11602.361870097142;11605.245659032136;11616.070714342832;11619.709507262301;4165.396017577435;4804.855854324306;4907.263910413395;4970.105381031377;4987.875303117451;9883.901160988442;9918.454485003394;9946.552405141785;9948.602055917827;9960.601575937026;5391.973040134852;5454;5456.836294911159;5471.644343117685;5472.124460086367;4446.399736035659;4620.5379462053525;4819.542143496362;4939.777710003059;4951.257311403318;3361.7646764726724;3976.224636195914;4014.6788256939376;4118.19695159441;4163.854265100707;1161.7792619402455;1347.6698208938872;1348.3393137362818;1349.595121463557;1367.5213675213595;896.8116695494019;911.40758506971;919.2783664823215;943.3443756589242;944.4569372610777;5745.109508026179;5948.7323070461935;5988.640681559121;6019.608725432833;6128.908066379008;439.28396713357023;458.87151597552736;461.19291240329426;468.2928777545862;481.33575665581276;3886.630770076838;3894.805259737002;3955.149642826806;3991.9201615967518;3996;10308.32995855282;10735.57057717698;10744.892551074388;10782.191335649857;10869.565217391373;9106.817863642822;10727.892721072845;10773.192010599236;10847.077998370227;10883.401412922207;7629.7329593463965;8257.720053391733;8267.007959044953;8272;8290.544020078954;5874.383189765013;6025.698715064317;6070.1198326241565;6111.694415279308;6180.938190618216;9798.167155791838;9933.27282338746;10202.897971020195;10205.795884082425;10214.029667181903;9944.105030547285;10230;10268.64059757905;10363.430011349146;10512.211584131068;6916.481263905127;7132.215456299699;7396.445266604918;7419.443541734284;7580.317771400601;8356.373272004503;8451.23938845507;8560.315174786127;8566.957165214135;8570.700025499078;7437.665305061177;7489.70041198346;7564;7666.156722760604;7701.460897737329;7310.19587845326;7348.52234604749;7358.816029599303;7496.62516874165;7545.886811697938;7066.4700147490175;7073.363397294268;7158.785236442915;7178.174509931216;7199.208087110518;4299.527052024212;4923.310736496822;4951.727654978867;5023.34696489454;5159.251908473326;4224.197402493639;4377.562243775725;4915.238138088602;5041.546260836542;5097.133487307241 03:03:43 INFO - PROCESS | 1671 | |4;prototype.html;315.3897208900776;320.0877499127487;327.9344131173765;331.8971118953125;334.9095744149078;4425.77871106445;5138.946515964228;5274.683518988863;5346.411894691586;5380.112281473562;4890.926636100452;5110.182370820669;5171.818986335479;5254.57963362931;5327.973360133204;3263.738900887935;3432.7940323580665;3488.4418493041053;4015.8393664253394;4137.482814648169;4002.779847108408;4060.3503439449696;4075.470188875442;4090;4103.610157035077;4015.558288588253;4036.636702696757;4046.5751096134923;4063.2279866825356;4074.470318858544;3244.5619841321354;3754.3617585010616;4055.9797201013807;4056.533498647649;4069.6570131856415;3071.877124915001;3153.6057992750907;3271.8691252349877;3428.5714285714284;3569.411047177213;3963.4938723285254;4481.417415735966;4606.447226332828;5021.899562008739;5049.621278404097;4388.7147335423415;4494.505604383515;4644.930326045112;4655.045715628322;4706.482286948433;5518.779248830042;5524.425538721485;5551.916721249185;5552.91670624941;5583.431621454136;3363.915902102467;3380.7295416366633;3899.805009749501;3924.136689928211;3936.724429289951;5491.395946445888;5680.857978550569;5853.64878107315;5857.560682948753;5893;3733.4959780429836;3950.9802450987563;3968.345223038224;3995.9001024974027;4013.9598604013586;3594.6944509716445;3683.594804571495;4019.738716983387;4045.3931910213705;4081.1838731764674;4651.395318608627;4652.837150699709;4659.347691323217;4690.624750019991;4692.741899195509;5334.359876814729;5575.435299762074;5594.908633143819;5605.243292155506;5637.082227851686;1294.0747365633617;1297.7209899871384;1310.842698876141;1313.4155300891011;1316.1642357103308;794.3486341200161;833.8576150673616;850.4174640371366;892.8883889513811;904.941218774036;4889.315495830587;5096.745162741848;5145.713571607098;5270.8155214567305;5291.682499050069;5218.608604354688;5784.843031393738;5801.448862358069;5823.737931793079;5871.32479764824;4617.069677310684;5009.799608015638;5100.852308230618;5157.349648112643;5285.497877701612;2617.358747106971;2692.474967381381;2701.648785657891;2850.8026628815946;2878.7553057990303;4973.50264973507;5139.8458046258675;5233.371995360581;5665.433456654301;5715.114012375906;4928.901421971541;5012.899742005139;5541.501264886179;5643.827126556305;5671.631343962629;4939.703038087307;5044.924326135111;5444.646098003617;5620.887582248414;5697.515711164515;4844.481960302304;5108.66793658411;5475.370332411664;5514.338279406497;5545.25324522774;4666.8133274668635;4973.975130124326;5343.809038294281;5405.945940540544;5483.780648774164;5050.863555699938;5067.175816706057;5161.7677204526635;5295;5656.462636049733;3315.055209265348;3684.299983003221;3709.387950988165;3779.433085037266;3798.753081049723;4650.558196971282;5130.510353921269;5208.0365132450615;5381.27352807366;5475.178723191553;4702.858914232578;5063.974680126576;5290.497402746733;5298.70857102863;5384.838454846361;4948.727819969936;5067.46791586878;5198.012377648235;5239.292695486213;5270.288511050959;4919.3358896549935;5117.411497677815;5124.410692770379;5159.587233021274;5173.741312934413;4398.582134697327;5423.484768946943;5431.646942948696;5441;5444.26502422168;5180.658475283346;5324;5356.973215133899;5470.343558772973;5495.313085864267 03:03:43 INFO - PROCESS | 1671 | |5;yui.html;662.5660192573863;736.9109169786653;755.4938191411753;755.9395248380126;769.6575024114271;14911.552653420387;15164.924175379121;15822.762658560116;15868.206589670528;15875.3649854006;14511.709765804677;14612.707745845104;14682.11907285561;14705.897057720687;14713.705725885502;720.2329519062208;740.5001623903844;905.2938707807921;924.7133388649506;926.1895841139003;891.3493150000503;921.1755478846428;924.6809850601566;927.4806108579184;929.0013235771539;968.9418634881895;973.0707174648194;974.3861367338567;976.0824824664793;976.9023097690246;739.4897520710746;932.2448816458623;966.2752935298527;968.5593055159885;969.6509256667593;710.8258476673196;715.8067321823079;717.8743719849058;718.4001358865354;718.4109030594914;14499.927500362535;15430.691386172213;15444.382224710998;15448.304826282843;15467;12506.749865002648;12546.560870369492;12561.937190314082;12750.87249127506;12920.54778082772;9124.45253284805;10933.194309972507;11162.386068766296;11398.259113157617;11544.365059921702;4001.5998400160042;4013.7190396671954;4022.0950286185666;4050.1697152083752;4307.224699554079;77232.84150737744;79340.60329698373;90141.74645633846;90179;90295.54852225762;9369.62521499146;9377.1091746284;9483.525823708926;9646.42121472714;9992.150667193224;21315.36053918385;21484;21504.354869353945;21573.46066348354;21575.02912368947;7086.893696594555;7372.483926125227;7410.407167426593;7567.9243207567215;7929.563873986871;3995.081131339808;4036.7577945323374;4053.2298863215897;4112.252292775558;4344.413504176959;3460.013896039667;3565.108722819295;3593.5508061492314;3598.7660802047785;3603.765755225902;658.4962503684685;660.2638058564665;664.299164381582;669.6383952665506;670.1622971285892;481.05712802906;491.81556916156444;537.6450552497095;577.5264283287747;602.9397060294024;7815.29662330393;7847.921520784833;8008.519488830689;8021.598920053974;8042.396820238506;512.4258298368921;558.7848678258819;568.0938902450584;580.4253788749108;581.4040608376448;3682.2234239419386;3745.737798354089;3750.624937506282;3804.6575808176844;3855.7108216883844;93088;93629;94031;94798;95631.52184239174;79423.20576794274;79598;80988;82443.17556824475;86208.81016303567;68298.3170168292;68679.31320686829;70258.64870675614;72655.54688906299;75197.24802752012;6798.354156355138;8163.510189388656;8265.214804593554;8349.916500834914;8484.533350665773;41942;42066.369004464344;43641.78179109084;43732.56267437285;45380;54207.10275140351;55459.722701386236;58811;61004.694976524836;62005.68997154985;11050.778984420105;11094.778104438028;11202.327860328407;11234;11561.421928903354;11625.495517397023;11844.881551184379;11922.76154476923;11925.10561707858;12837.871621283668;9657.71026869195;11091.445427728742;11339.829902551302;11423.543058277908;11737.765244695229;11210.103191744804;11390.886091139315;11423.828642570203;11519.424028798694;11950.581729639422;9924.702258932244;10682.664666916635;11149.163812713916;11343.546258149581;11644.941775291069;9485.620575176916;9491.38306010107;9560.76098097553;9648.662296819577;9680;8705.868237129132;9585.90414095878;9611.903880961101;9666.854997174907;9742.9025709742 03:03:43 INFO - PROCESS | 1671 | __end_tp_report 03:03:43 INFO - PROCESS | 1671 | __start_cc_report 03:03:43 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1341 03:03:43 INFO - PROCESS | 1671 | __end_cc_report 03:03:43 INFO - PROCESS | 1671 | __startTimestamp1462701823572__endTimestamp 03:03:43 INFO - PROCESS | 1671 | 03:03:43 INFO - PROCESS | 1671 | ------- Summary: start ------- 03:03:43 INFO - PROCESS | 1671 | Number of tests: 6 03:03:43 INFO - PROCESS | 1671 | 03:03:43 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5714.19 Median:5956.49 stddev:652.34 (11.0%) stddev-sans-first:614.67 03:03:43 INFO - PROCESS | 1671 | Values: 2858.6 3429.8 3461.6 3465.2 3504.8 5572.9 5581.9 5633.1 5648.9 5732.3 5654.3 5712.5 5722.7 5752.5 5769.9 5162.6 5202.6 5221.4 5244.1 5272.3 5276.6 5293.3 5295.1 5297.2 5299.0 5132.4 5189.6 5199.3 5205.0 5275.0 5286.2 5295.4 5303.0 5306.5 5379.5 5091.9 5103.2 5135.8 5221.6 5227.4 5508.3 5537.3 5593.6 5603.3 5618.0 4516.6 5206.6 5257.8 6240.4 6240.5 6140.1 6181.2 6265.9 6272.5 6279.8 5826.4 5850.8 5912.8 5933.6 5963.3 6223.7 6242.6 6255.4 6265.4 6276.2 6024.5 6093.1 6118.1 6147.9 6187.4 6246.5 6258.2 6268.0 6288.8 6290.0 6176.4 6222.8 6247.1 6253.2 6272.7 6139.8 6142.3 6146.9 6170.6 6270.0 4836.2 4883.0 4901.4 4905.5 4948.8 4362.0 4398.1 4410.2 4413.1 4474.2 5632.9 5685.8 5686.3 5744.5 5746.0 6265.6 6272.1 6272.6 6288.9 6298.4 5717.3 5736.1 5756.3 5757.3 5805.0 5259.0 5342.8 5351.8 5368.5 5408.5 6301.7 6336.4 6343.5 6373.6 6383.8 6126.6 6241.3 6249.0 6249.2 6251.5 6169.0 6236.6 6279.6 6338.5 6449.3 6171.3 6185.5 6193.9 6200.8 6217.6 6136.2 6233.2 6258.7 6273.7 6280.9 6034.5 6084.9 6100.8 6110.2 6144.3 6103.4 6112.4 6124.1 6124.3 6141.9 5754.9 5825.7 5956.5 5977.2 5979.2 5130.7 5941.7 6012.5 6084.8 6113.9 4963.3 5980.0 6008.8 6009.8 6019.3 03:03:43 INFO - PROCESS | 1671 | 03:03:43 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:68202.86 Median:19112.45 stddev:145451.97 (761.0%) stddev-sans-first:145771.17 03:03:43 INFO - PROCESS | 1671 | Values: 497.1 500.7 512.8 514.7 547.9 29479.3 30647.0 41791.0 43077.8 43092.0 43809.8 44216.8 44240.0 44376.6 44542.1 8826.1 9820.8 10004.5 10018.8 10038.9 8852.3 11792.5 12032.7 12056.4 12218.9 12011.7 12065.4 12077.0 12088.2 12112.8 8847.5 11750.4 11930.7 11988.1 12060.1 9836.9 9950.6 11857.3 11874.0 11897.0 40022.8 40406.2 40450.0 40465.0 40489.8 3045.9 3047.0 3361.7 3541.6 3640.6 28497.7 28692.4 28832.4 29535.1 29651.3 2687.1 2736.2 2789.5 2793.8 2834.7 490279.0 490845.0 491415.0 491489.0 491799.0 60192.1 60776.0 60884.4 60892.7 60907.7 64251.7 64375.7 64463.0 64507.4 64515.7 16862.6 17110.3 17126.1 17148.7 17186.0 10261.7 12348.5 12965.5 13165.0 13169.7 16150.8 16193.5 16212.9 16263.1 16293.0 7389.9 7391.5 7426.0 7439.7 7504.5 919.4 1011.3 1038.9 1076.7 1104.6 28703.6 29127.7 29224.0 29821.0 29919.9 1056.9 1073.2 1108.3 1157.6 1215.5 15116.5 15172.8 15190.4 15477.9 15506.9 631397.0 631851.0 636481.0 636560.0 637577.0 60004.0 60031.7 60054.7 60199.4 60385.4 1314.6 1453.7 1480.9 1495.1 1514.2 30082.7 30142.2 30298.1 30545.9 30813.7 49972.0 50649.5 50708.2 50743.7 50837.0 483371.0 500576.0 501109.0 502505.0 502718.0 32899.0 38437.6 38478.6 38747.2 39189.4 44983.0 45230.0 45349.3 45788.5 45876.3 37672.8 37942.0 38049.6 38049.8 38465.0 31545.7 34683.3 37229.4 37490.6 37722.8 36439.8 36952.7 37142.8 37530.8 37813.4 32357.5 32380.4 32783.5 32835.5 32993.0 19093.8 24219.8 24757.4 24877.8 25033.6 03:03:43 INFO - PROCESS | 1671 | 03:03:43 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:36277.79 Median:21173.05 stddev:105739.17 (499.4%) stddev-sans-first:106001.46 03:03:43 INFO - PROCESS | 1671 | Values: 392.5 397.2 397.3 398.2 419.7 14676.6 20220.9 21073.3 21272.8 21308.5 15625.6 17618.6 19690.6 20247.2 20645.0 5742.6 6140.4 6152.5 6207.9 6234.1 5794.5 6122.1 6176.3 6181.7 6187.8 6104.8 6142.7 6156.7 6159.2 6162.5 5999.0 6062.7 6140.1 6143.5 6160.6 5331.1 5334.0 5342.6 5350.3 5383.1 11197.0 14044.9 23681.8 24144.0 25649.0 2142.6 2155.8 2229.7 2300.6 2415.0 9761.3 10834.6 11871.0 13312.7 15339.8 1631.6 1683.7 1702.1 1733.1 1784.4 121658.4 124142.8 128723.0 130626.0 130908.3 11544.8 11596.5 11707.9 11714.1 11860.1 12550.9 15191.3 15217.5 15226.9 15232.4 7440.7 7499.6 8651.9 8795.4 8818.1 8640.5 8644.4 8659.0 8743.7 8763.8 4588.2 4609.0 4627.5 4645.7 4704.7 1685.4 1902.4 1938.1 1960.4 1970.8 879.8 894.2 923.0 1004.6 1044.5 18623.1 19441.9 20619.0 20676.9 20816.4 954.7 959.0 989.5 1004.1 1021.0 8737.6 10568.5 10863.5 10873.5 10880.5 616567.0 624607.0 637983.0 640978.0 641470.0 130767.0 132774.0 135045.3 135545.3 142009.0 1007.1 1014.8 1024.3 1036.2 1038.1 14283.2 17413.4 17792.3 18024.4 18114.1 52171.7 53302.0 54056.7 56057.2 56771.7 79670.2 81204.0 81489.2 83137.0 83180.2 6890.7 6975.7 7372.3 7418.1 8142.9 10562.0 12121.6 12470.0 12665.3 12731.4 10403.8 10672.0 10771.2 10895.7 11022.9 10482.5 10572.5 10595.5 10772.4 10776.4 10840.0 11030.8 11067.7 11275.2 11326.3 7251.4 7282.2 7309.5 7465.8 7490.3 9754.8 10956.2 11034.7 11086.2 11105.4 03:03:43 INFO - PROCESS | 1671 | 03:03:43 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5717.17 Median:4919.27 stddev:3208.85 (65.2%) stddev-sans-first:3201.48 03:03:43 INFO - PROCESS | 1671 | Values: 1404.3 1587.8 1612.0 1826.9 1955.6 7696.8 8525.6 8778.1 8797.0 8882.3 8566.1 8744.3 8753.5 8760.5 8785.0 2169.2 2227.6 2400.2 2462.0 2490.6 2089.8 2520.5 2525.0 2526.4 2527.6 2448.6 2466.9 2494.4 2495.6 2500.1 2143.4 2370.7 2439.3 2440.6 2483.4 2458.6 2485.6 2486.9 2494.1 2495.4 8603.5 8680.6 8690.7 8691.0 8712.3 5206.2 5365.3 5401.8 5407.6 5410.4 2878.5 3397.6 3638.9 3644.9 3657.9 1035.3 1046.4 1047.0 1047.2 1055.1 11513.9 11602.4 11605.2 11616.1 11619.7 4165.4 4804.9 4907.3 4970.1 4987.9 9883.9 9918.5 9946.6 9948.6 9960.6 5392.0 5454.0 5456.8 5471.6 5472.1 4446.4 4620.5 4819.5 4939.8 4951.3 3361.8 3976.2 4014.7 4118.2 4163.9 1161.8 1347.7 1348.3 1349.6 1367.5 896.8 911.4 919.3 943.3 944.5 5745.1 5948.7 5988.6 6019.6 6128.9 439.3 458.9 461.2 468.3 481.3 3886.6 3894.8 3955.1 3991.9 3996.0 10308.3 10735.6 10744.9 10782.2 10869.6 9106.8 10727.9 10773.2 10847.1 10883.4 7629.7 8257.7 8267.0 8272.0 8290.5 5874.4 6025.7 6070.1 6111.7 6180.9 9798.2 9933.3 10202.9 10205.8 10214.0 9944.1 10230.0 10268.6 10363.4 10512.2 6916.5 7132.2 7396.4 7419.4 7580.3 8356.4 8451.2 8560.3 8567.0 8570.7 7437.7 7489.7 7564.0 7666.2 7701.5 7310.2 7348.5 7358.8 7496.6 7545.9 7066.5 7073.4 7158.8 7178.2 7199.2 4299.5 4923.3 4951.7 5023.3 5159.3 4224.2 4377.6 4915.2 5041.5 5097.1 03:03:43 INFO - PROCESS | 1671 | 03:03:43 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4419.90 Median:5033.41 stddev:1337.29 (26.6%) stddev-sans-first:1305.08 03:03:43 INFO - PROCESS | 1671 | Values: 315.4 320.1 327.9 331.9 334.9 4425.8 5138.9 5274.7 5346.4 5380.1 4890.9 5110.2 5171.8 5254.6 5328.0 3263.7 3432.8 3488.4 4015.8 4137.5 4002.8 4060.4 4075.5 4090.0 4103.6 4015.6 4036.6 4046.6 4063.2 4074.5 3244.6 3754.4 4056.0 4056.5 4069.7 3071.9 3153.6 3271.9 3428.6 3569.4 3963.5 4481.4 4606.4 5021.9 5049.6 4388.7 4494.5 4644.9 4655.0 4706.5 5518.8 5524.4 5551.9 5552.9 5583.4 3363.9 3380.7 3899.8 3924.1 3936.7 5491.4 5680.9 5853.6 5857.6 5893.0 3733.5 3951.0 3968.3 3995.9 4014.0 3594.7 3683.6 4019.7 4045.4 4081.2 4651.4 4652.8 4659.3 4690.6 4692.7 5334.4 5575.4 5594.9 5605.2 5637.1 1294.1 1297.7 1310.8 1313.4 1316.2 794.3 833.9 850.4 892.9 904.9 4889.3 5096.7 5145.7 5270.8 5291.7 5218.6 5784.8 5801.4 5823.7 5871.3 4617.1 5009.8 5100.9 5157.3 5285.5 2617.4 2692.5 2701.6 2850.8 2878.8 4973.5 5139.8 5233.4 5665.4 5715.1 4928.9 5012.9 5541.5 5643.8 5671.6 4939.7 5044.9 5444.6 5620.9 5697.5 4844.5 5108.7 5475.4 5514.3 5545.3 4666.8 4974.0 5343.8 5405.9 5483.8 5050.9 5067.2 5161.8 5295.0 5656.5 3315.1 3684.3 3709.4 3779.4 3798.8 4650.6 5130.5 5208.0 5381.3 5475.2 4702.9 5064.0 5290.5 5298.7 5384.8 4948.7 5067.5 5198.0 5239.3 5270.3 4919.3 5117.4 5124.4 5159.6 5173.7 4398.6 5423.5 5431.6 5441.0 5444.3 5180.7 5324.0 5357.0 5470.3 5495.3 03:03:43 INFO - PROCESS | 1671 | 03:03:43 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:18213.12 Median:61505.19 stddev:25836.15 (42.0%) stddev-sans-first:25875.02 03:03:43 INFO - PROCESS | 1671 | Values: 662.6 736.9 755.5 755.9 769.7 14911.6 15164.9 15822.8 15868.2 15875.4 14511.7 14612.7 14682.1 14705.9 14713.7 720.2 740.5 905.3 924.7 926.2 891.3 921.2 924.7 927.5 929.0 968.9 973.1 974.4 976.1 976.9 739.5 932.2 966.3 968.6 969.7 710.8 715.8 717.9 718.4 718.4 14499.9 15430.7 15444.4 15448.3 15467.0 12506.7 12546.6 12561.9 12750.9 12920.5 9124.5 10933.2 11162.4 11398.3 11544.4 4001.6 4013.7 4022.1 4050.2 4307.2 77232.8 79340.6 90141.7 90179.0 90295.5 9369.6 9377.1 9483.5 9646.4 9992.2 21315.4 21484.0 21504.4 21573.5 21575.0 7086.9 7372.5 7410.4 7567.9 7929.6 3995.1 4036.8 4053.2 4112.3 4344.4 3460.0 3565.1 3593.6 3598.8 3603.8 658.5 660.3 664.3 669.6 670.2 481.1 491.8 537.6 577.5 602.9 7815.3 7847.9 8008.5 8021.6 8042.4 512.4 558.8 568.1 580.4 581.4 3682.2 3745.7 3750.6 3804.7 3855.7 93088.0 93629.0 94031.0 94798.0 95631.5 79423.2 79598.0 80988.0 82443.2 86208.8 68298.3 68679.3 70258.6 72655.5 75197.2 6798.4 8163.5 8265.2 8349.9 8484.5 41942.0 42066.4 43641.8 43732.6 45380.0 54207.1 55459.7 58811.0 61004.7 62005.7 11050.8 11094.8 11202.3 11234.0 11561.4 11625.5 11844.9 11922.8 11925.1 12837.9 9657.7 11091.4 11339.8 11423.5 11737.8 11210.1 11390.9 11423.8 11519.4 11950.6 9924.7 10682.7 11149.2 11343.5 11644.9 9485.6 9491.4 9560.8 9648.7 9680.0 8705.9 9585.9 9611.9 9666.9 9742.9 03:03:43 INFO - PROCESS | 1671 | -------- Summary: end -------- 03:03:43 INFO - PROCESS | 1671 | 03:03:44 INFO - TEST-INFO | 1671: exit 0 03:03:44 INFO - TEST-OK | dromaeo_css | took 1115620ms 03:03:44 INFO - TEST-START | kraken 03:03:44 INFO - operating with platform_type : mac_ 03:03:44 INFO - Initialising browser for kraken test... 03:03:44 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpbRazlJ/profile http://localhost:49280/getInfo.html) 03:03:46 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 03:03:46 INFO - PROCESS | 1678 | colorDepth:24 03:03:46 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 03:03:46 INFO - PROCESS | 1678 | __metrics 03:03:46 INFO - PROCESS | 1678 | 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" 03:03:46 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:03:47 INFO - TEST-INFO | 1678: exit 0 03:03:47 INFO - Browser initialized. 03:03:47 INFO - Running cycle 1/1 for kraken test... 03:03:47 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpbRazlJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:03:59 INFO - PROCESS | 1680 | RSS: Main: 238981120 03:03:59 INFO - PROCESS | 1680 | 03:04:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:02 INFO - PROCESS | 1680 | RSS: Main: 286822400 03:04:02 INFO - PROCESS | 1680 | 03:04:05 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 03:04:06 INFO - PROCESS | 1680 | RSS: Main: 295198720 03:04:06 INFO - PROCESS | 1680 | 03:04:09 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 03:04:10 INFO - PROCESS | 1680 | RSS: Main: 383131648 03:04:10 INFO - PROCESS | 1680 | 03:04:12 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 03:04:12 INFO - PROCESS | 1680 | RSS: Main: 283709440 03:04:12 INFO - PROCESS | 1680 | 03:04:14 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:04:15 INFO - PROCESS | 1680 | RSS: Main: 287854592 03:04:15 INFO - PROCESS | 1680 | 03:04:18 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 03:04:18 INFO - PROCESS | 1680 | RSS: Main: 322572288 03:04:18 INFO - PROCESS | 1680 | 03:04:21 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 03:04:22 INFO - PROCESS | 1680 | RSS: Main: 324812800 03:04:22 INFO - PROCESS | 1680 | 03:04:25 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 03:04:25 INFO - PROCESS | 1680 | RSS: Main: 323452928 03:04:25 INFO - PROCESS | 1680 | 03:04:27 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:04:27 INFO - PROCESS | 1680 | RSS: Main: 312549376 03:04:27 INFO - PROCESS | 1680 | 03:04:29 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:04:30 INFO - PROCESS | 1680 | RSS: Main: 320593920 03:04:30 INFO - PROCESS | 1680 | 03:04:32 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:04:33 INFO - PROCESS | 1680 | RSS: Main: 321249280 03:04:33 INFO - PROCESS | 1680 | 03:04:36 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:04:36 INFO - PROCESS | 1680 | RSS: Main: 326144000 03:04:36 INFO - PROCESS | 1680 | 03:04:39 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:04:40 INFO - PROCESS | 1680 | RSS: Main: 323575808 03:04:40 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 03:04:42 INFO - PROCESS | 1680 | RSS: Main: 370249728 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | __start_tp_report 03:04:42 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 03:04:42 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 03:04:42 INFO - PROCESS | 1680 | |i|pagename|runs| 03:04:42 INFO - PROCESS | 1680 | |0;ai-astar;112;104;113;112;112;112;111;112;112;112 03:04:42 INFO - PROCESS | 1680 | |1;audio-beat-detection;154;119;140;163;118;141;133;114;168;134 03:04:42 INFO - PROCESS | 1680 | |2;audio-dft;185;184;182;184;184;180;181;183;185;184 03:04:42 INFO - PROCESS | 1680 | |3;audio-fft;78;73;58;105;57;61;66;58;109;57 03:04:42 INFO - PROCESS | 1680 | |4;audio-oscillator;90;102;101;80;79;77;101;80;79;100 03:04:42 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;87;85;87;84;87;85;88;84;91;85 03:04:42 INFO - PROCESS | 1680 | |6;imaging-darkroom;94;93;93;94;93;92;93;93;92;94 03:04:42 INFO - PROCESS | 1680 | |7;imaging-desaturate;83;84;84;84;99;133;143;83;83;83 03:04:42 INFO - PROCESS | 1680 | |8;json-parse-financial;59;62;62;60;60;60;60;76;59;57 03:04:42 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;64;70;80;74;69;68;68;66;67;70 03:04:42 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;84;93;106;83;85;84;81;85;84;84 03:04:42 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;133;137;138;136;133;133;135;137;180;136 03:04:42 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;212;210;166;169;168;224;223;170;168;178 03:04:42 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;66;67;67;66;65;65;68;66;66;65 03:04:42 INFO - PROCESS | 1680 | __end_tp_report 03:04:42 INFO - PROCESS | 1680 | __start_cc_report 03:04:42 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1976 03:04:42 INFO - PROCESS | 1680 | __end_cc_report 03:04:42 INFO - PROCESS | 1680 | __startTimestamp1462701882326__endTimestamp 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | ------- Summary: start ------- 03:04:42 INFO - PROCESS | 1680 | Number of tests: 14 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.57 (2.3%) stddev-sans-first:2.71 03:04:42 INFO - PROCESS | 1680 | Values: 112.0 104.0 113.0 112.0 112.0 112.0 111.0 112.0 112.0 112.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:140.50 stddev:18.77 (13.4%) stddev-sans-first:19.04 03:04:42 INFO - PROCESS | 1680 | Values: 154.0 119.0 140.0 163.0 118.0 141.0 133.0 114.0 168.0 134.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.69 (0.9%) stddev-sans-first:1.66 03:04:42 INFO - PROCESS | 1680 | Values: 185.0 184.0 182.0 184.0 184.0 180.0 181.0 183.0 185.0 184.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:72.20 Median:59.50 stddev:19.70 (33.1%) stddev-sans-first:20.79 03:04:42 INFO - PROCESS | 1680 | Values: 78.0 73.0 58.0 105.0 57.0 61.0 66.0 58.0 109.0 57.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:79.00 stddev:10.98 (13.9%) stddev-sans-first:11.64 03:04:42 INFO - PROCESS | 1680 | Values: 90.0 102.0 101.0 80.0 79.0 77.0 101.0 80.0 79.0 100.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:2.16 (2.5%) stddev-sans-first:2.28 03:04:42 INFO - PROCESS | 1680 | Values: 87.0 85.0 87.0 84.0 87.0 85.0 88.0 84.0 91.0 85.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 03:04:42 INFO - PROCESS | 1680 | Values: 94.0 93.0 93.0 94.0 93.0 92.0 93.0 93.0 92.0 94.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:83.50 stddev:22.84 (27.4%) stddev-sans-first:23.74 03:04:42 INFO - PROCESS | 1680 | Values: 83.0 84.0 84.0 84.0 99.0 133.0 143.0 83.0 83.0 83.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:60.00 stddev:5.30 (8.8%) stddev-sans-first:5.54 03:04:42 INFO - PROCESS | 1680 | Values: 59.0 62.0 62.0 60.0 60.0 60.0 60.0 76.0 59.0 57.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:69.50 stddev:4.53 (6.5%) stddev-sans-first:4.32 03:04:42 INFO - PROCESS | 1680 | Values: 64.0 70.0 80.0 74.0 69.0 68.0 68.0 66.0 67.0 70.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:84.00 stddev:7.40 (8.8%) stddev-sans-first:7.77 03:04:42 INFO - PROCESS | 1680 | Values: 84.0 93.0 106.0 83.0 85.0 84.0 81.0 85.0 84.0 84.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:139.80 Median:136.50 stddev:14.24 (10.4%) stddev-sans-first:14.89 03:04:42 INFO - PROCESS | 1680 | Values: 133.0 137.0 138.0 136.0 133.0 133.0 135.0 137.0 180.0 136.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.80 Median:194.00 stddev:25.04 (12.9%) stddev-sans-first:25.11 03:04:42 INFO - PROCESS | 1680 | Values: 212.0 210.0 166.0 169.0 168.0 224.0 223.0 170.0 168.0 178.0 03:04:42 INFO - PROCESS | 1680 | 03:04:42 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 03:04:42 INFO - PROCESS | 1680 | Values: 66.0 67.0 67.0 66.0 65.0 65.0 68.0 66.0 66.0 65.0 03:04:42 INFO - PROCESS | 1680 | -------- Summary: end -------- 03:04:42 INFO - PROCESS | 1680 | 03:04:43 INFO - TEST-INFO | 1680: exit 0 03:04:43 INFO - TEST-OK | kraken | took 58950ms 03:04:43 INFO - SUITE-END | took 1174s 03:04:43 INFO - Completed test suite (00:19:34) 03:04:43 INFO - javascript benchmark 03:04:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2858.58550510176, 3429.794212347261, 3461.6365281645444, 3465.237647717502, 3504.754667173297, 5572.9411411831725, 5581.916271255935, 5633.070543360341, 5648.943510564898, 5732.340780810203, 5654.321481422225, 5712.485876271135, 5722.713864306778, 5752.539796816255, 5769.884602307951, 5162.5611822995, 5202.557782588485, 5221.425643179247, 5244.134717771563, 5272.340957380327, 5276.604254680895, 5293.338332708411, 5295.126304159809, 5297.205419187114, 5299.019681358942, 5132.409772876111, 5189.584833213353, 5199.298094757197, 5205.0, 5274.971380580789, 5286.154215325549, 5295.391030031557, 5303.018941495836, 5306.548943339821, 5379.542738867201, 5091.8981620367385, 5103.183490641517, 5135.8202462914, 5221.6083793715625, 5227.424983251839, 5508.3114610673665, 5537.307836520395, 5593.608447408683, 5603.2715746953045, 5618.0, 4516.616087632555, 5206.583473322163, 5257.842264732026, 6240.438360547572, 6240.5319601030105, 6140.074827791158, 6181.227346581677, 6265.87468250637, 6272.529560282951, 6279.780207692709, 5826.359100498942, 5850.765969361178, 5912.822615321505, 5933.58464907461, 5963.344032156459, 6223.657698826518, 6242.5942313749465, 6255.40573645503, 6265.4361107500545, 6276.184096067574, 6024.457798798129, 6093.116498107838, 6118.051701986199, 6147.924113280158, 6187.381261873777, 6246.531510136758, 6258.217722784652, 6267.96866015667, 6288.842778930563, 6289.96855015722, 6176.382361763787, 6222.844428889314, 6247.125402443661, 6253.155823963798, 6272.717727702265, 6139.815805525842, 6142.2839182294965, 6146.8935591593045, 6170.598911070766, 6269.965455699758, 4836.177849765549, 4882.95117048825, 4901.387326584177, 4905.533974272438, 4948.752562371867, 4362.0, 4398.054418300081, 4410.184115938561, 4413.13943780966, 4474.194644963938, 5632.887342253132, 5685.829425117253, 5686.317641883001, 5744.540436765049, 5745.9712701437065, 6265.561410701298, 6272.059191121369, 6272.6236425814595, 6288.937110628926, 6298.433141017239, 5717.342505611825, 5736.082226843685, 5756.251687280626, 5757.251557297524, 5804.970975145097, 5259.0, 5342.839714808562, 5351.759170837322, 5368.4631536846, 5408.540274076748, 6301.6534090625455, 6336.398042185985, 6343.492520598249, 6373.5857169283845, 6383.776567820104, 6126.5711400203345, 6241.3134555197985, 6249.0, 6249.218847644044, 6251.53113516479, 6169.0, 6236.563440559299, 6279.591826531444, 6338.492920566251, 6449.322821103717, 6171.259448866164, 6185.536084793748, 6193.938060619336, 6200.782972595937, 6217.626942383471, 6136.1821086261625, 6233.189685340877, 6258.718357673824, 6273.686315684106, 6280.937190628218, 6034.456898879122, 6084.939150608614, 6100.786541944698, 6110.16388879229, 6144.262688477412, 6103.428585615474, 6112.419320164578, 6124.142620033288, 6124.265088189446, 6141.88493709207, 5754.9424505754405, 5825.650460972355, 5956.493698035715, 5977.193078934288, 5979.162917191509, 5130.7178105203075, 5941.673207973603, 6012.518998480198, 6084.756609735561, 6113.871604717183, 4963.255511673278, 5980.0, 6008.819735407945, 6009.759609615567, 6019.337872833898], "value": 5672.924769484162, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.0903247057882, 500.7070863544826, 512.825639282644, 514.6989011428315, 547.9178123281508, 29479.263018424524, 30647.0, 41791.0, 43077.78461107698, 43092.0, 43809.7809510952, 44216.77891610545, 44240.0, 44376.556234437645, 44542.10915781691, 8826.073262307462, 9820.754481137976, 10004.549795259194, 10018.849717254247, 10038.949805250964, 8852.291816654684, 11792.528298868036, 12032.699182520419, 12056.397180141003, 12218.93890530546, 12011.699707507294, 12065.3967301635, 12077.0, 12088.21426607272, 12112.757744845097, 8847.469151850844, 11750.353730544817, 11930.701732456671, 11988.100892433102, 12060.095492838029, 9836.90163098367, 9950.552225149886, 11857.288562686266, 11874.0, 11897.0, 40022.799886000386, 40406.19187616231, 40450.0, 40465.0, 40489.79755101206, 3045.8629361678554, 3047.024952015356, 3361.680640339164, 3541.592716837571, 3640.6359364063646, 28497.715022849712, 28692.41410770682, 28832.423351533063, 29535.113946581638, 29651.258718531994, 2687.1401151631485, 2736.179146256115, 2789.4839454700946, 2793.7625301849366, 2834.702356252605, 490279.0, 490845.0, 491414.99999999645, 491489.0, 491799.0, 60192.09711854326, 60776.0, 60884.39115608876, 60892.695536522035, 60907.69546152241, 64251.678741605996, 64375.67812160909, 64463.0, 64507.35492645107, 64515.709685806956, 16862.57843553921, 17110.315587376615, 17126.14369281531, 17148.74276885823, 17186.0, 10261.74345641365, 12348.506059757688, 12965.546205882749, 13165.0, 13169.736605268032, 16150.838491615168, 16193.514194574182, 16212.918935405247, 16263.129740080043, 16293.0, 7389.889151662729, 7391.519551229153, 7426.0, 7439.665215065335, 7504.474686771982, 919.3748251189255, 1011.3426272922512, 1038.8649475568127, 1076.7455913860265, 1104.5802595014004, 28703.56944645832, 29127.7087229125, 29224.0, 29821.0, 29919.85040074829, 1056.8849863394194, 1073.2326386633595, 1108.3405373802575, 1157.571698471554, 1215.4834195466892, 15116.470923517623, 15172.772408413883, 15190.392384304725, 15477.922610386877, 15506.922465387826, 631397.0, 631851.0, 636481.0, 636560.0, 637577.0, 60004.0, 60031.699841500515, 60054.69972650109, 60199.39800602025, 60385.39614603798, 1314.59247633234, 1453.7165252775608, 1480.8667219950257, 1495.1477657735204, 1514.167208035554, 30082.68263664655, 30142.24722687703, 30298.091057268317, 30545.93089241866, 30813.69186308198, 49972.0, 50649.49350506448, 50708.23937641012, 50743.74628126836, 50837.0, 483371.0, 500576.0, 501109.0, 502505.0, 502718.0, 32899.0, 38437.615623843405, 38478.6152138475, 38747.225055498166, 39189.41215881707, 44983.0, 45230.0, 45349.319760204286, 45788.54211457976, 45876.31185532286, 37672.811635941645, 37942.0, 38049.61950380571, 38049.80975095107, 38465.0, 31545.738170472923, 34683.30633387268, 37229.441558377184, 37490.625093749804, 37722.81138594289, 36439.81780091082, 36952.706655266935, 37142.814285929475, 37530.812345938095, 37813.43279850749, 32357.514637280932, 32380.35239295154, 32783.50824737678, 32835.50746738753, 32993.0, 19093.809061909204, 24219.75780242175, 24757.381065473506, 24877.751222487543, 25033.624495632215], "value": 20272.839264673312, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.5387669488351, 397.2353220051401, 397.3384315115333, 398.23340070364543, 419.7145940760284, 14676.559703208894, 20220.89889550552, 21073.262435814748, 21272.829994350323, 21308.46728831778, 15625.609359765984, 17618.647627047485, 19690.606187876234, 20247.190112395485, 20645.0, 5742.598018138732, 6140.35526269742, 6152.538559608025, 6207.906881396782, 6234.096056071867, 5794.478496935275, 6122.081687746829, 6176.289726681444, 6181.690915454232, 6187.752489900398, 6104.816855494342, 6142.662153581551, 6156.6613836238985, 6159.168512250833, 6162.5069994400565, 5999.0, 6062.696865156725, 6140.1096840958035, 6143.508519318466, 6160.599561028519, 5331.093714068617, 5333.973330133324, 5342.5993050521365, 5350.3312085989255, 5383.111786555253, 11197.0, 14044.859551404457, 23681.76318236813, 24144.0, 25649.0, 2142.582196471689, 2155.7736437673975, 2229.6544035674497, 2300.5858945389823, 2414.9758502414925, 9761.316707830454, 10834.566617335297, 11871.0, 13312.667183320398, 15339.772818174519, 1631.6002579368012, 1683.6632673465233, 1702.131912724507, 1733.0901276829638, 1784.3843873863495, 121658.39170804177, 124142.75857241402, 128723.0, 130626.0, 130908.3454582721, 11544.826827597593, 11596.536138554533, 11707.882921170849, 11714.121440891968, 11860.110491713156, 12550.874491255154, 15191.316390762442, 15217.543473695807, 15226.923865380602, 15232.390704371923, 7440.73957411488, 7499.587522686195, 8651.91348086511, 8795.428297160664, 8818.11818818113, 8640.524771137647, 8644.438111522732, 8658.956705216433, 8743.650253989896, 8763.780905477286, 4588.220002599566, 4608.953910460919, 4627.537246275346, 4645.74448405341, 4704.717716936994, 1685.4101064627282, 1902.3627084926427, 1938.0503553258802, 1960.431474872271, 1970.7930667280016, 879.7668617816283, 894.2383504496132, 923.0077666508396, 1004.5680357446369, 1044.472541366605, 18623.068846557617, 19441.902790485958, 20619.0, 20676.896615517126, 20816.375508734764, 954.6992697300328, 958.9664361747306, 989.5497548615363, 1004.1364426593124, 1020.979020979021, 8737.56312184401, 10568.52441640128, 10863.45682715861, 10873.510692018877, 10880.510377033053, 616567.0, 624607.0, 637983.0, 640978.0, 641470.0, 130767.0, 132774.0, 135045.3247733755, 135545.322273388, 142009.0, 1007.09361574582, 1014.7868947520956, 1024.3393011507535, 1036.160709825044, 1038.055369613648, 14283.214423206824, 17413.386179631223, 17792.288308467516, 18024.36914707979, 18114.09429528492, 52171.73914130405, 53302.0, 54056.72971635117, 56057.197140142016, 56771.71614141903, 79670.20329796628, 81204.0, 81489.18510814816, 83137.0, 83180.16819831966, 6890.724371025103, 6975.720971161097, 7372.336489715952, 7418.146913105062, 8142.918570814216, 10562.032451664785, 12121.575744848888, 12470.0, 12665.290185825164, 12731.427085781348, 10403.84394234072, 10672.0, 10771.246012779031, 10895.727606809893, 11022.944885275523, 10482.475876206312, 10572.524236409534, 10595.523201455799, 10772.407517586607, 10776.407297598707, 10840.0, 11030.779384412428, 11067.667969960914, 11275.15436342294, 11326.263792853439, 7251.41988640918, 7282.162551306667, 7309.451791115794, 7465.776026719207, 7490.325870671666, 9754.75613109678, 10956.178286628376, 11034.724131896766, 11086.168537359892, 11105.444727763417], "value": 9511.623375934294, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.3118871752845, 1587.8174009988852, 1611.9597010074747, 1826.9451916442504, 1955.550223448606, 7696.8075798105, 8525.573721313933, 8778.122187781219, 8796.95601521993, 8882.333824963122, 8566.05773364931, 8744.256738177246, 8753.474791512514, 8760.474371537697, 8785.0, 2169.2190811307914, 2227.6324406472913, 2400.219928523228, 2461.975380246197, 2490.6139548369943, 2089.8119169274764, 2520.5463016656995, 2524.9873750631314, 2526.39366552028, 2527.6208568714746, 2448.6449464827588, 2466.938326541842, 2494.3639371960126, 2495.5757521221344, 2500.1499490173337, 2143.3784202581232, 2370.6918100646976, 2439.2804122783823, 2440.5606990741658, 2483.416397146669, 2458.606622940321, 2485.6395822605623, 2486.9378265543323, 2494.0896572751, 2495.3512086857368, 8603.526806025666, 8680.609372578248, 8690.652373905035, 8691.043985161627, 8712.346574006911, 5206.167013277857, 5365.3293338332705, 5401.756920938567, 5407.621466497347, 5410.377806552257, 2878.481873262812, 3397.5922889253447, 3638.9272214555563, 3644.908877278063, 3657.8719744808805, 1035.321864178964, 1046.4140081554353, 1046.9738256543646, 1047.2250534604414, 1055.0662663542744, 11513.942430287794, 11602.361870097142, 11605.245659032136, 11616.070714342832, 11619.709507262301, 4165.396017577435, 4804.855854324306, 4907.263910413395, 4970.105381031377, 4987.875303117451, 9883.901160988442, 9918.454485003394, 9946.552405141785, 9948.602055917827, 9960.601575937026, 5391.973040134852, 5454.0, 5456.836294911159, 5471.644343117685, 5472.124460086367, 4446.399736035659, 4620.5379462053525, 4819.542143496362, 4939.777710003059, 4951.257311403318, 3361.7646764726724, 3976.224636195914, 4014.6788256939376, 4118.19695159441, 4163.854265100707, 1161.7792619402455, 1347.6698208938872, 1348.3393137362818, 1349.595121463557, 1367.5213675213595, 896.8116695494019, 911.40758506971, 919.2783664823215, 943.3443756589242, 944.4569372610777, 5745.109508026179, 5948.7323070461935, 5988.640681559121, 6019.608725432833, 6128.908066379008, 439.28396713357023, 458.87151597552736, 461.19291240329426, 468.2928777545862, 481.33575665581276, 3886.630770076838, 3894.805259737002, 3955.149642826806, 3991.9201615967518, 3996.0, 10308.32995855282, 10735.57057717698, 10744.892551074388, 10782.191335649857, 10869.565217391373, 9106.817863642822, 10727.892721072845, 10773.192010599236, 10847.077998370227, 10883.401412922207, 7629.7329593463965, 8257.720053391733, 8267.007959044953, 8272.0, 8290.544020078954, 5874.383189765013, 6025.698715064317, 6070.1198326241565, 6111.694415279308, 6180.938190618216, 9798.167155791838, 9933.27282338746, 10202.897971020195, 10205.795884082425, 10214.029667181903, 9944.105030547285, 10230.0, 10268.64059757905, 10363.430011349146, 10512.211584131068, 6916.481263905127, 7132.215456299699, 7396.445266604918, 7419.443541734284, 7580.317771400601, 8356.373272004503, 8451.23938845507, 8560.315174786127, 8566.957165214135, 8570.700025499078, 7437.665305061177, 7489.70041198346, 7564.0, 7666.156722760604, 7701.460897737329, 7310.19587845326, 7348.52234604749, 7358.816029599303, 7496.62516874165, 7545.886811697938, 7066.4700147490175, 7073.363397294268, 7158.785236442915, 7178.174509931216, 7199.208087110518, 4299.527052024212, 4923.310736496822, 4951.727654978867, 5023.34696489454, 5159.251908473326, 4224.197402493639, 4377.562243775725, 4915.238138088602, 5041.546260836542, 5097.133487307241], "value": 4531.927420882218, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.3897208900776, 320.0877499127487, 327.9344131173765, 331.8971118953125, 334.9095744149078, 4425.77871106445, 5138.946515964228, 5274.683518988863, 5346.411894691586, 5380.112281473562, 4890.926636100452, 5110.182370820669, 5171.818986335479, 5254.57963362931, 5327.973360133204, 3263.738900887935, 3432.7940323580665, 3488.4418493041053, 4015.8393664253394, 4137.482814648169, 4002.779847108408, 4060.3503439449696, 4075.470188875442, 4090.0, 4103.610157035077, 4015.558288588253, 4036.636702696757, 4046.5751096134923, 4063.2279866825356, 4074.470318858544, 3244.5619841321354, 3754.3617585010616, 4055.9797201013807, 4056.533498647649, 4069.6570131856415, 3071.877124915001, 3153.6057992750907, 3271.8691252349877, 3428.5714285714284, 3569.411047177213, 3963.4938723285254, 4481.417415735966, 4606.447226332828, 5021.899562008739, 5049.621278404097, 4388.7147335423415, 4494.505604383515, 4644.930326045112, 4655.045715628322, 4706.482286948433, 5518.779248830042, 5524.425538721485, 5551.916721249185, 5552.91670624941, 5583.431621454136, 3363.915902102467, 3380.7295416366633, 3899.805009749501, 3924.136689928211, 3936.724429289951, 5491.395946445888, 5680.857978550569, 5853.64878107315, 5857.560682948753, 5893.0, 3733.4959780429836, 3950.9802450987563, 3968.345223038224, 3995.9001024974027, 4013.9598604013586, 3594.6944509716445, 3683.594804571495, 4019.738716983387, 4045.3931910213705, 4081.1838731764674, 4651.395318608627, 4652.837150699709, 4659.347691323217, 4690.624750019991, 4692.741899195509, 5334.359876814729, 5575.435299762074, 5594.908633143819, 5605.243292155506, 5637.082227851686, 1294.0747365633617, 1297.7209899871384, 1310.842698876141, 1313.4155300891011, 1316.1642357103308, 794.3486341200161, 833.8576150673616, 850.4174640371366, 892.8883889513811, 904.941218774036, 4889.315495830587, 5096.745162741848, 5145.713571607098, 5270.8155214567305, 5291.682499050069, 5218.608604354688, 5784.843031393738, 5801.448862358069, 5823.737931793079, 5871.32479764824, 4617.069677310684, 5009.799608015638, 5100.852308230618, 5157.349648112643, 5285.497877701612, 2617.358747106971, 2692.474967381381, 2701.648785657891, 2850.8026628815946, 2878.7553057990303, 4973.50264973507, 5139.8458046258675, 5233.371995360581, 5665.433456654301, 5715.114012375906, 4928.901421971541, 5012.899742005139, 5541.501264886179, 5643.827126556305, 5671.631343962629, 4939.703038087307, 5044.924326135111, 5444.646098003617, 5620.887582248414, 5697.515711164515, 4844.481960302304, 5108.66793658411, 5475.370332411664, 5514.338279406497, 5545.25324522774, 4666.8133274668635, 4973.975130124326, 5343.809038294281, 5405.945940540544, 5483.780648774164, 5050.863555699938, 5067.175816706057, 5161.7677204526635, 5295.0, 5656.462636049733, 3315.055209265348, 3684.299983003221, 3709.387950988165, 3779.433085037266, 3798.753081049723, 4650.558196971282, 5130.510353921269, 5208.0365132450615, 5381.27352807366, 5475.178723191553, 4702.858914232578, 5063.974680126576, 5290.497402746733, 5298.70857102863, 5384.838454846361, 4948.727819969936, 5067.46791586878, 5198.012377648235, 5239.292695486213, 5270.288511050959, 4919.3358896549935, 5117.411497677815, 5124.410692770379, 5159.587233021274, 5173.741312934413, 4398.582134697327, 5423.484768946943, 5431.646942948696, 5441.0, 5444.26502422168, 5180.658475283346, 5324.0, 5356.973215133899, 5470.343558772973, 5495.313085864267], "value": 3998.4771563714203, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.5660192573863, 736.9109169786653, 755.4938191411753, 755.9395248380126, 769.6575024114271, 14911.552653420387, 15164.924175379121, 15822.762658560116, 15868.206589670528, 15875.3649854006, 14511.709765804677, 14612.707745845104, 14682.11907285561, 14705.897057720687, 14713.705725885502, 720.2329519062208, 740.5001623903844, 905.2938707807921, 924.7133388649506, 926.1895841139003, 891.3493150000503, 921.1755478846428, 924.6809850601566, 927.4806108579184, 929.0013235771539, 968.9418634881895, 973.0707174648194, 974.3861367338567, 976.0824824664793, 976.9023097690246, 739.4897520710746, 932.2448816458623, 966.2752935298527, 968.5593055159885, 969.6509256667593, 710.8258476673196, 715.8067321823079, 717.8743719849058, 718.4001358865354, 718.4109030594914, 14499.927500362535, 15430.691386172213, 15444.382224710998, 15448.304826282843, 15467.0, 12506.749865002648, 12546.560870369492, 12561.937190314082, 12750.87249127506, 12920.54778082772, 9124.45253284805, 10933.194309972507, 11162.386068766296, 11398.259113157617, 11544.365059921702, 4001.5998400160042, 4013.7190396671954, 4022.0950286185666, 4050.1697152083752, 4307.224699554079, 77232.84150737744, 79340.60329698373, 90141.74645633846, 90179.0, 90295.54852225762, 9369.62521499146, 9377.1091746284, 9483.525823708926, 9646.42121472714, 9992.150667193224, 21315.36053918385, 21484.0, 21504.354869353945, 21573.46066348354, 21575.02912368947, 7086.893696594555, 7372.483926125227, 7410.407167426593, 7567.9243207567215, 7929.563873986871, 3995.081131339808, 4036.7577945323374, 4053.2298863215897, 4112.252292775558, 4344.413504176959, 3460.013896039667, 3565.108722819295, 3593.5508061492314, 3598.7660802047785, 3603.765755225902, 658.4962503684685, 660.2638058564665, 664.299164381582, 669.6383952665506, 670.1622971285892, 481.05712802906, 491.81556916156444, 537.6450552497095, 577.5264283287747, 602.9397060294024, 7815.29662330393, 7847.921520784833, 8008.519488830689, 8021.598920053974, 8042.396820238506, 512.4258298368921, 558.7848678258819, 568.0938902450584, 580.4253788749108, 581.4040608376448, 3682.2234239419386, 3745.737798354089, 3750.624937506282, 3804.6575808176844, 3855.7108216883844, 93088.0, 93629.0, 94031.0, 94798.0, 95631.52184239174, 79423.20576794274, 79598.0, 80988.0, 82443.17556824475, 86208.81016303567, 68298.3170168292, 68679.31320686829, 70258.64870675614, 72655.54688906299, 75197.24802752012, 6798.354156355138, 8163.510189388656, 8265.214804593554, 8349.916500834914, 8484.533350665773, 41942.0, 42066.369004464344, 43641.78179109084, 43732.56267437285, 45380.0, 54207.10275140351, 55459.722701386236, 58811.0, 61004.694976524836, 62005.68997154985, 11050.778984420105, 11094.778104438028, 11202.327860328407, 11234.0, 11561.421928903354, 11625.495517397023, 11844.881551184379, 11922.76154476923, 11925.10561707858, 12837.871621283668, 9657.71026869195, 11091.445427728742, 11339.829902551302, 11423.543058277908, 11737.765244695229, 11210.103191744804, 11390.886091139315, 11423.828642570203, 11519.424028798694, 11950.581729639422, 9924.702258932244, 10682.664666916635, 11149.163812713916, 11343.546258149581, 11644.941775291069, 9485.620575176916, 9491.38306010107, 9560.76098097553, 9648.662296819577, 9680.0, 8705.868237129132, 9585.90414095878, 9611.903880961101, 9666.854997174907, 9742.9025709742], "value": 6896.7701651599045, "unit": "score"}], "name": "dromaeo_css", "value": 7177.523008151833, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 113.0, 112.0, 112.0, 112.0, 111.0, 112.0, 112.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 119.0, 140.0, 163.0, 118.0, 141.0, 133.0, 114.0, 168.0, 134.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 182.0, 184.0, 184.0, 180.0, 181.0, 183.0, 185.0, 184.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 58.0, 105.0, 57.0, 61.0, 66.0, 58.0, 109.0, 57.0], "value": 72.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 102.0, 101.0, 80.0, 79.0, 77.0, 101.0, 80.0, 79.0, 100.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 87.0, 85.0, 88.0, 84.0, 91.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 93.0, 92.0, 93.0, 93.0, 92.0, 94.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 84.0, 99.0, 133.0, 143.0, 83.0, 83.0, 83.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 62.0, 60.0, 60.0, 60.0, 60.0, 76.0, 59.0, 57.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 70.0, 80.0, 74.0, 69.0, 68.0, 68.0, 66.0, 67.0, 70.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 93.0, 106.0, 83.0, 85.0, 84.0, 81.0, 85.0, 84.0, 84.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 137.0, 138.0, 136.0, 133.0, 133.0, 135.0, 137.0, 180.0, 136.0], "value": 139.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 210.0, 166.0, 169.0, 168.0, 224.0, 223.0, 170.0, 168.0, 178.0], "value": 188.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 65.0, 65.0, 68.0, 66.0, 66.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1481.8999999999999, "alertThreshold": 2.0}]} 03:04:43 INFO - Return code: 0 03:04:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:04:43 ERROR - # TBPL SUCCESS # 03:04:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:04:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:04:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:04:43 INFO - 2016-05-08 03:04:43.644 screenresolution[1684:9889] starting screenresolution argv=screenresolution get 03:04:43 INFO - 2016-05-08 03:04:43.647 screenresolution[1684:9889] Display 0: 1600x1200x32@60 03:04:43 INFO - 2016-05-08 03:04:43.653 screenresolution[1685:9892] starting screenresolution argv=screenresolution list 03:04:43 INFO - Available Modes on Display 0 03:04:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:04:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:04:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:04:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:04:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:04:43 INFO - Intel Iris: 03:04:43 INFO - Chipset Model: Intel Iris 03:04:43 INFO - Type: GPU 03:04:43 INFO - Bus: Built-In 03:04:43 INFO - VRAM (Dynamic, Max): 1536 MB 03:04:43 INFO - Vendor: Intel (0x8086) 03:04:43 INFO - Device ID: 0x0a2e 03:04:43 INFO - Revision ID: 0x0009 03:04:43 INFO - Displays: 03:04:43 INFO - Display: 03:04:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:04:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:04:43 INFO - Display Serial Number: 1600x1200 60 03:04:43 INFO - Main Display: Yes 03:04:43 INFO - Mirror: Off 03:04:43 INFO - Online: Yes 03:04:43 INFO - Rotation: Supported 03:04:43 INFO - Return code: 0 03:04:43 INFO - Running post-action listener: _resource_record_post_action 03:04:43 INFO - ##### 03:04:43 INFO - ##### Finished run-tests step (success) 03:04:43 INFO - ##### 03:04:43 INFO - Running post-run listener: _resource_record_post_run 03:04:44 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37807616; Write bytes: 699534848; Read time: 434; Write time: 2050 03:04:44 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 389548544; Write bytes: 378283520; Read time: 28307; Write time: 658 03:04:44 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30780928; Write bytes: 295702528; Read time: 350; Write time: 1291 03:04:44 INFO - Running post-run listener: _upload_blobber_files 03:04:44 INFO - Blob upload gear active. 03:04:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:04:44 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 03:04:44 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'] 03:04:44 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 03:04:45 INFO - (blobuploader) - INFO - Open directory for files ... 03:04:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:04:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:04:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:46 INFO - (blobuploader) - INFO - Done attempting. 03:04:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:04:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:04:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:47 INFO - (blobuploader) - INFO - Done attempting. 03:04:47 INFO - (blobuploader) - INFO - Iteration through files over. 03:04:47 INFO - Return code: 0 03:04:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:04:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:04:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a47f05777e7eddecb814c7cfc15602466c067b203b94d5ccd466dd7ac776bd3100ae5d29f403bc19e404b6fc4ea8abcef5485dd05faf8e5c2a37938aec34ed03", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d861242c2f32fb16ab6894d1bab6e96966d69987df477049217f783c823d9e5aad009a9becf19bf1ac2c98b70a1b90d39d665b7686926fb2c585ecbfaec4b71d"} 03:04:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:04:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:04:47 INFO - Contents: 03:04:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a47f05777e7eddecb814c7cfc15602466c067b203b94d5ccd466dd7ac776bd3100ae5d29f403bc19e404b6fc4ea8abcef5485dd05faf8e5c2a37938aec34ed03", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d861242c2f32fb16ab6894d1bab6e96966d69987df477049217f783c823d9e5aad009a9becf19bf1ac2c98b70a1b90d39d665b7686926fb2c585ecbfaec4b71d"} 03:04:47 INFO - Running post-run listener: copy_logs_to_upload_dir 03:04:47 INFO - Copying logs to upload dir... 03:04:47 INFO - mkdir: /builds/slave/test/build/upload/logs 03:04:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.908178 ========= master_lag: 59.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 9 secs) (at 2016-05-08 03:05:47.502508) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-08 03:05:47.505904) ========= 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.zU1sYfp61i/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.834nK67YRB/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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/a47f05777e7eddecb814c7cfc15602466c067b203b94d5ccd466dd7ac776bd3100ae5d29f403bc19e404b6fc4ea8abcef5485dd05faf8e5c2a37938aec34ed03", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d861242c2f32fb16ab6894d1bab6e96966d69987df477049217f783c823d9e5aad009a9becf19bf1ac2c98b70a1b90d39d665b7686926fb2c585ecbfaec4b71d"} build_url:https://queue.taskcluster.net/v1/task/RMY_V1yrQXOEKBblqD9A5Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011185 build_url: 'https://queue.taskcluster.net/v1/task/RMY_V1yrQXOEKBblqD9A5Q/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/a47f05777e7eddecb814c7cfc15602466c067b203b94d5ccd466dd7ac776bd3100ae5d29f403bc19e404b6fc4ea8abcef5485dd05faf8e5c2a37938aec34ed03", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d861242c2f32fb16ab6894d1bab6e96966d69987df477049217f783c823d9e5aad009a9becf19bf1ac2c98b70a1b90d39d665b7686926fb2c585ecbfaec4b71d"}' ========= master_lag: 0.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-08 03:05:47.584697) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-05-08 03:05:47.584983) ========= 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.zU1sYfp61i/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.834nK67YRB/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.005117 ========= master_lag: 3.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-05-08 03:05:50.609431) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-05-08 03:05:50.617758) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-05-08 03:05:51.879950) ========= ========= Total master_lag: 62.80 =========