builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0082 starttime: 1462623153.23 results: success (0) buildid: 20160507030302 builduid: 0378f2d024784863ae9051233455da48 revision: 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:33.232072) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:33.232530) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:33.232841) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:33.305996) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:33.306299) ========= 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.duKc8jXHAU/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.3E16YzOu01/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-07 05:12:33-- 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% 12.1M=0.001s 2016-05-07 05:12:33 (12.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.543089 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:33.866636) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:33.866920) ========= 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.duKc8jXHAU/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.3E16YzOu01/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.020498 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:33.915488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:33.915868) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.duKc8jXHAU/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.3E16YzOu01/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-07 05:12:33,990 truncating revision to first 12 chars 2016-05-07 05:12:33,990 Setting DEBUG logging. 2016-05-07 05:12:33,990 attempt 1/10 2016-05-07 05:12:33,990 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/70dfd4254cf9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-07 05:12:34,718 unpacking tar archive at: mozilla-central-70dfd4254cf9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.977226 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:34.911471) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:34.911995) ========= script_repo_revision: 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:34.912661) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:34.913117) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-07 05:12:34.930460) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-05-07 05:12:34.930965) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.duKc8jXHAU/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.3E16YzOu01/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:12:35 INFO - MultiFileLogger online at 20160507 05:12:35 in /builds/slave/test 05:12:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:12:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:12:35 INFO - {'append_to_log': False, 05:12:35 INFO - 'base_work_dir': '/builds/slave/test', 05:12:35 INFO - 'blob_upload_branch': 'Firefox', 05:12:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:12:35 INFO - 'branch': 'Firefox', 05:12:35 INFO - 'buildbot_json_path': 'buildprops.json', 05:12:35 INFO - 'config_files': ('talos/mac_config.py',), 05:12:35 INFO - 'default_actions': ('clobber', 05:12:35 INFO - 'read-buildbot-config', 05:12:35 INFO - 'download-and-extract', 05:12:35 INFO - 'populate-webroot', 05:12:35 INFO - 'create-virtualenv', 05:12:35 INFO - 'install', 05:12:35 INFO - 'run-tests'), 05:12:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:12:35 INFO - 'download_minidump_stackwalk': True, 05:12:35 INFO - 'download_symbols': 'ondemand', 05:12:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:12:35 INFO - 'tooltool.py': '/tools/tooltool.py', 05:12:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:12:35 INFO - '/tools/misc-python/virtualenv.py')}, 05:12:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:12:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:12:35 INFO - 'installer_path': 'installer.exe', 05:12:35 INFO - 'log_level': 'info', 05:12:35 INFO - 'log_name': 'talos', 05:12:35 INFO - 'log_to_console': True, 05:12:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:12:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:12:35 INFO - 'opt_config_files': (), 05:12:35 INFO - 'pip_index': False, 05:12:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:12:35 INFO - 'cmd': ('bash', 05:12:35 INFO - '-c', 05:12:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:12:35 INFO - 'enabled': True, 05:12:35 INFO - 'halt_on_failure': False, 05:12:35 INFO - 'name': 'check_screen_resolution'},), 05:12:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:12:35 INFO - 'cmd': ('bash', 05:12:35 INFO - '-c', 05:12:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:12:35 INFO - 'enabled': True, 05:12:35 INFO - 'halt_on_failure': False, 05:12:35 INFO - 'name': 'check_screen_resolution'},), 05:12:35 INFO - 'run_cmd_checks_enabled': True, 05:12:35 INFO - 'sps_profile': False, 05:12:35 INFO - 'sps_profile_interval': 0, 05:12:35 INFO - 'suite': 'dromaeojs-e10s', 05:12:35 INFO - 'system_bits': '32', 05:12:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:12:35 INFO - 'title': 't-yosemite-r7-0082', 05:12:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:12:35 INFO - 'use_talos_json': True, 05:12:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:12:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:12:35 INFO - 'work_dir': 'build'} 05:12:35 INFO - ##### 05:12:35 INFO - ##### Running clobber step. 05:12:35 INFO - ##### 05:12:35 INFO - Running pre-action listener: _resource_record_pre_action 05:12:35 INFO - Running main action method: clobber 05:12:35 INFO - rmtree: /builds/slave/test/build 05:12:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:12:36 INFO - Running post-action listener: _resource_record_post_action 05:12:36 INFO - ##### 05:12:36 INFO - ##### Finished clobber step (success) 05:12:36 INFO - ##### 05:12:36 INFO - ##### 05:12:36 INFO - ##### Running read-buildbot-config step. 05:12:36 INFO - ##### 05:12:36 INFO - Running pre-action listener: _resource_record_pre_action 05:12:36 INFO - Running main action method: read_buildbot_config 05:12:36 INFO - Using buildbot properties: 05:12:36 INFO - { 05:12:36 INFO - "project": "", 05:12:36 INFO - "product": "firefox", 05:12:36 INFO - "script_repo_revision": "production", 05:12:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:12:36 INFO - "repository": "", 05:12:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:12:36 INFO - "buildid": "20160507030302", 05:12:36 INFO - "stage_platform": "macosx64", 05:12:36 INFO - "basedir": "/builds/slave/test", 05:12:36 INFO - "nightly_build": "True", 05:12:36 INFO - "buildnumber": 2, 05:12:36 INFO - "slavename": "t-yosemite-r7-0082", 05:12:36 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:12:36 INFO - "platform": "yosemite_r7", 05:12:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:12:36 INFO - "branch": "mozilla-central", 05:12:36 INFO - "repo_path": "mozilla-central", 05:12:36 INFO - "revision": "70dfd4254cf977be2000b41f7a6b97eaada8e2a0", 05:12:36 INFO - "pgo_build": "False", 05:12:36 INFO - "builduid": "0378f2d024784863ae9051233455da48", 05:12:36 INFO - "slavebuilddir": "test" 05:12:36 INFO - } 05:12:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:12:36 INFO - Running post-action listener: _resource_record_post_action 05:12:36 INFO - ##### 05:12:36 INFO - ##### Finished read-buildbot-config step (success) 05:12:36 INFO - ##### 05:12:36 INFO - ##### 05:12:36 INFO - ##### Running download-and-extract step. 05:12:36 INFO - ##### 05:12:36 INFO - Running pre-action listener: _resource_record_pre_action 05:12:36 INFO - Running main action method: download_and_extract 05:12:36 INFO - mkdir: /builds/slave/test/build/tests 05:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:36 INFO - https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:12:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:12:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:12:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/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 05:12:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/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 05:12:39 INFO - Downloaded 1199 bytes. 05:12:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:12:39 INFO - Using the following test package requirements: 05:12:39 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:12:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:39 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:12:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:39 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:12:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:39 INFO - u'jsshell-mac.zip'], 05:12:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:39 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:12:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:12:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:39 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:12:39 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:39 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:12:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:39 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:12:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:12:39 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:12:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:12:39 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')]} 05:12:39 INFO - https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:12:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:12:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:12:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/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 05:12:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/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 05:12:43 INFO - Downloaded 18145170 bytes. 05:12:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:12:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:12:46 INFO - Return code: 0 05:12:46 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 05:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:46 INFO - https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:12:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:12:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:12:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/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 05:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/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 05:12:50 INFO - Downloaded 18145170 bytes. 05:12: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'] 05:12: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 05:12:54 INFO - Return code: 0 05:12:54 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')]} 05:12:54 INFO - https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:12:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:12:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:12:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/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 05:12:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/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 05:12:55 INFO - Downloaded 10840152 bytes. 05:12:55 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'] 05:12:55 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 05:12:55 INFO - Return code: 0 05:12: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')]} 05:12:55 INFO - https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:12:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:12:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:12:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:12:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/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 05:13:05 INFO - Downloaded 110582666 bytes. 05:13:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:05 INFO - mkdir: /builds/slave/test/properties 05:13:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:13:05 INFO - Writing to file /builds/slave/test/properties/build_url 05:13:05 INFO - Contents: 05:13:05 INFO - build_url:https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:13:06 INFO - Running post-action listener: _resource_record_post_action 05:13:06 INFO - Running post-action listener: set_extra_try_arguments 05:13:06 INFO - ##### 05:13:06 INFO - ##### Finished download-and-extract step (success) 05:13:06 INFO - ##### 05:13:06 INFO - ##### 05:13:06 INFO - ##### Running populate-webroot step. 05:13:06 INFO - ##### 05:13:06 INFO - Running pre-action listener: _resource_record_pre_action 05:13:06 INFO - Running main action method: populate_webroot 05:13:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:13:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:13:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:13:06 INFO - '--tppagecycles', 05:13:06 INFO - '7'], 05:13:06 INFO - 'tests': ['tsvgm']}}, 05:13:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:13:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:13:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:13:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:13:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:13:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['damp', 'tps']}, 05:13:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'tests': ['damp', 'tps']}, 05:13:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['dromaeo_dom']}, 05:13:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:13:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'other-e10s': {'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['a11yr', 05:13:06 INFO - 'ts_paint', 05:13:06 INFO - 'tpaint', 05:13:06 INFO - 'sessionrestore', 05:13:06 INFO - 'sessionrestore_no_auto_restore', 05:13:06 INFO - 'tabpaint']}, 05:13:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['tsvgx', 05:13:06 INFO - 'tsvgr_opacity', 05:13:06 INFO - 'tart', 05:13:06 INFO - 'tscrollx', 05:13:06 INFO - 'cart']}, 05:13:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:13:06 INFO - 'tsvgr_opacity', 05:13:06 INFO - 'tart', 05:13:06 INFO - 'tscrollx', 05:13:06 INFO - 'cart']}, 05:13:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'talos_options': ['--disable-e10s'], 05:13:06 INFO - 'tests': ['tp5o']}, 05:13:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'tests': ['tp5o']}, 05:13:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'talos_options': ['--disable-e10s', 05:13:06 INFO - '--xperf_path', 05:13:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:06 INFO - 'tests': ['tp5n']}, 05:13:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:13:06 INFO - 'talos_options': ['--xperf_path', 05:13:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:13:06 INFO - 'tests': ['tp5n']}}, 05:13:06 INFO - 'talos.zip': {'path': '', 05:13:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:13:06 INFO - Running post-action listener: _resource_record_post_action 05:13:06 INFO - ##### 05:13:06 INFO - ##### Finished populate-webroot step (success) 05:13:06 INFO - ##### 05:13:06 INFO - ##### 05:13:06 INFO - ##### Running create-virtualenv step. 05:13:06 INFO - ##### 05:13:06 INFO - Running pre-action listener: _resource_record_pre_action 05:13:06 INFO - Running main action method: create_virtualenv 05:13:06 INFO - Creating virtualenv /builds/slave/test/build/venv 05:13:06 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 05:13:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:13:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:13:06 INFO - Using real prefix '/tools/python27' 05:13:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:13:07 INFO - Installing distribute.............................................................................................................................................................................................done. 05:13:10 INFO - Installing pip.................done. 05:13:10 INFO - Return code: 0 05:13:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a2cadc20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204bd30>, '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 0x101e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102042b20>, '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.duKc8jXHAU/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.3E16YzOu01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:13: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', 'pip>=1.5'] in /builds/slave/test/build 05:13: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 pip>=1.5 05:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duKc8jXHAU/Render', 05:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:10 INFO - 'HOME': '/Users/cltbld', 05:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:10 INFO - 'LOGNAME': 'cltbld', 05:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:10 INFO - 'MOZ_NO_REMOTE': '1', 05:13:10 INFO - 'NO_EM_RESTART': '1', 05:13:10 INFO - 'PAGER': '/bin/cat', 05:13:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:10 INFO - 'PWD': '/builds/slave/test', 05:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:10 INFO - 'SHELL': '/bin/bash', 05:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3E16YzOu01/Listeners', 05:13:10 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:10 INFO - 'USER': 'cltbld', 05:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:10 INFO - 'XPC_FLAGS': '0x0', 05:13:10 INFO - 'XPC_SERVICE_NAME': '0', 05:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:10 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 05:13:10 INFO - Cleaning up... 05:13:10 INFO - Return code: 0 05:13:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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', '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 0x1021a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a2cadc20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204bd30>, '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 0x101e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102042b20>, '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.duKc8jXHAU/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.3E16YzOu01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:13: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', 'psutil>=0.7.1'] in /builds/slave/test/build 05:13: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 psutil>=0.7.1 05:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duKc8jXHAU/Render', 05:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:10 INFO - 'HOME': '/Users/cltbld', 05:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:10 INFO - 'LOGNAME': 'cltbld', 05:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:10 INFO - 'MOZ_NO_REMOTE': '1', 05:13:10 INFO - 'NO_EM_RESTART': '1', 05:13:10 INFO - 'PAGER': '/bin/cat', 05:13:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:10 INFO - 'PWD': '/builds/slave/test', 05:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:10 INFO - 'SHELL': '/bin/bash', 05:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3E16YzOu01/Listeners', 05:13:10 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:10 INFO - 'USER': 'cltbld', 05:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:10 INFO - 'XPC_FLAGS': '0x0', 05:13:10 INFO - 'XPC_SERVICE_NAME': '0', 05:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:10 INFO - Downloading/unpacking psutil>=0.7.1 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:13:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:14 INFO - Installing collected packages: psutil 05:13:14 INFO - Running setup.py install for psutil 05:13:14 INFO - building 'psutil._psutil_osx' extension 05:13:14 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 05:13:14 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 05:13:14 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 05:13:14 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 05:13:14 INFO - building 'psutil._psutil_posix' extension 05:13:14 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 05:13:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:13:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:13:14 INFO - ^ 05:13:14 INFO - 1 warning generated. 05:13:14 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 05:13:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:13:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:13:14 INFO - Successfully installed psutil 05:13:14 INFO - Cleaning up... 05:13:14 INFO - Return code: 0 05:13:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:14 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 0x1021a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a2cadc20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204bd30>, '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 0x101e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102042b20>, '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.duKc8jXHAU/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.3E16YzOu01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:13:14 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 05:13:14 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 05:13:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duKc8jXHAU/Render', 05:13:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:14 INFO - 'HOME': '/Users/cltbld', 05:13:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:14 INFO - 'LOGNAME': 'cltbld', 05:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:14 INFO - 'MOZ_NO_REMOTE': '1', 05:13:14 INFO - 'NO_EM_RESTART': '1', 05:13:14 INFO - 'PAGER': '/bin/cat', 05:13:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:14 INFO - 'PWD': '/builds/slave/test', 05:13:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:14 INFO - 'SHELL': '/bin/bash', 05:13:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3E16YzOu01/Listeners', 05:13:14 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:13:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:14 INFO - 'USER': 'cltbld', 05:13:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:14 INFO - 'XPC_FLAGS': '0x0', 05:13:14 INFO - 'XPC_SERVICE_NAME': '0', 05:13:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:13:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:13:17 INFO - Installing collected packages: mozsystemmonitor 05:13:17 INFO - Running setup.py install for mozsystemmonitor 05:13:17 INFO - Successfully installed mozsystemmonitor 05:13:17 INFO - Cleaning up... 05:13:17 INFO - Return code: 0 05:13:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:17 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 0x1021a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a2cadc20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204bd30>, '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 0x101e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102042b20>, '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.duKc8jXHAU/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.3E16YzOu01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:13:17 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 05:13:17 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 05:13:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duKc8jXHAU/Render', 05:13:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:17 INFO - 'HOME': '/Users/cltbld', 05:13:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:17 INFO - 'LOGNAME': 'cltbld', 05:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:17 INFO - 'MOZ_NO_REMOTE': '1', 05:13:17 INFO - 'NO_EM_RESTART': '1', 05:13:17 INFO - 'PAGER': '/bin/cat', 05:13:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:17 INFO - 'PWD': '/builds/slave/test', 05:13:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:17 INFO - 'SHELL': '/bin/bash', 05:13:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3E16YzOu01/Listeners', 05:13:17 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:13:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:17 INFO - 'USER': 'cltbld', 05:13:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:17 INFO - 'XPC_FLAGS': '0x0', 05:13:17 INFO - 'XPC_SERVICE_NAME': '0', 05:13:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:18 INFO - Downloading/unpacking blobuploader==1.2.4 05:13:18 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 05:13:18 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 05:13:18 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 05:13:18 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 05:13:18 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 05:13:18 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 05:13:20 INFO - Downloading blobuploader-1.2.4.tar.gz 05:13:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:13:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:13:20 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 05:13:20 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 05:13:20 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 05:13:20 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 05:13:20 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 05:13:20 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 05:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:13:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:21 INFO - Downloading docopt-0.6.1.tar.gz 05:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:13:21 INFO - Installing collected packages: blobuploader, requests, docopt 05:13:21 INFO - Running setup.py install for blobuploader 05:13:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:13:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:13:22 INFO - Running setup.py install for requests 05:13:22 INFO - Running setup.py install for docopt 05:13:22 INFO - Successfully installed blobuploader requests docopt 05:13:22 INFO - Cleaning up... 05:13:22 INFO - Return code: 0 05:13:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:22 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 0x1021a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a2cadc20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204bd30>, '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 0x101e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102042b20>, '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.duKc8jXHAU/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.3E16YzOu01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:13:22 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 05:13:22 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 05:13:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duKc8jXHAU/Render', 05:13:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:22 INFO - 'HOME': '/Users/cltbld', 05:13:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:22 INFO - 'LOGNAME': 'cltbld', 05:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:22 INFO - 'MOZ_NO_REMOTE': '1', 05:13:22 INFO - 'NO_EM_RESTART': '1', 05:13:22 INFO - 'PAGER': '/bin/cat', 05:13:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:22 INFO - 'PWD': '/builds/slave/test', 05:13:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:22 INFO - 'SHELL': '/bin/bash', 05:13:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3E16YzOu01/Listeners', 05:13:22 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:13:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:22 INFO - 'USER': 'cltbld', 05:13:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:22 INFO - 'XPC_FLAGS': '0x0', 05:13:22 INFO - 'XPC_SERVICE_NAME': '0', 05:13:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-mIsgc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-mZOIIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-vG5bMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-jG7_CA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-8Lygdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-IJIKet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-9U2uyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-hWmOyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Qe0gXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-qVGfxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:24 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-_0jyp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:24 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-1uVmC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:24 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-SFX5Fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:24 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-jTpnLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:24 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-XrCIRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:24 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Jfclit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:24 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-QOxhqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:13:24 INFO - Running setup.py install for manifestparser 05:13:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:13:24 INFO - Running setup.py install for mozcrash 05:13:25 INFO - Running setup.py install for mozdebug 05:13:25 INFO - Running setup.py install for mozdevice 05:13:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:13:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:13:25 INFO - Running setup.py install for mozfile 05:13:25 INFO - Running setup.py install for mozhttpd 05:13:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:13:25 INFO - Running setup.py install for mozinfo 05:13:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:13:25 INFO - Running setup.py install for mozInstall 05:13:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:13:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:13:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:13:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:13:25 INFO - Running setup.py install for mozleak 05:13:26 INFO - Running setup.py install for mozlog 05:13:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:13:26 INFO - Running setup.py install for moznetwork 05:13:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:13:26 INFO - Running setup.py install for mozprocess 05:13:26 INFO - Running setup.py install for mozprofile 05:13:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:13:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:13:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:13:26 INFO - Running setup.py install for mozrunner 05:13:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:13:26 INFO - Running setup.py install for mozscreenshot 05:13:27 INFO - Running setup.py install for moztest 05:13:27 INFO - Running setup.py install for mozversion 05:13:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:13:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:13:27 INFO - Cleaning up... 05:13:27 INFO - Return code: 0 05:13:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:27 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 0x1021a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a2cadc20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204bd30>, '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 0x101e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102042b20>, '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.duKc8jXHAU/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.3E16YzOu01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:13:27 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 05:13:27 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 05:13:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duKc8jXHAU/Render', 05:13:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:27 INFO - 'HOME': '/Users/cltbld', 05:13:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:27 INFO - 'LOGNAME': 'cltbld', 05:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:27 INFO - 'MOZ_NO_REMOTE': '1', 05:13:27 INFO - 'NO_EM_RESTART': '1', 05:13:27 INFO - 'PAGER': '/bin/cat', 05:13:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:27 INFO - 'PWD': '/builds/slave/test', 05:13:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:27 INFO - 'SHELL': '/bin/bash', 05:13:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3E16YzOu01/Listeners', 05:13:27 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:13:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:27 INFO - 'USER': 'cltbld', 05:13:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:27 INFO - 'XPC_FLAGS': '0x0', 05:13:27 INFO - 'XPC_SERVICE_NAME': '0', 05:13:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ObZR8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:27 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)) 05:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-qtOOGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:27 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)) 05:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-skb8XP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:27 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)) 05:13:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:27 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-pm5Stm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:28 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)) 05:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:28 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-E8bdM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:28 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)) 05:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:28 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-o1AeHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:28 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)) 05:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:28 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ke9QPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:28 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)) 05:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:28 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-tYbJcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:28 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)) 05:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:28 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-AfnDVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:28 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)) 05:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:28 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-jgR6Or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:28 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)) 05:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:28 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-_L2PRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:28 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)) 05:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:28 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-2nurW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:28 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)) 05:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:28 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-QhDwN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:29 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)) 05:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:29 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-XPVsQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:29 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)) 05:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:29 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-I9I6gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:29 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)) 05:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:29 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-zsuM6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:29 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)) 05:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:29 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-el7j8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:29 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)) 05:13:29 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)) 05:13:29 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)) 05:13:29 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)) 05:13:29 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)) 05:13:29 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)) 05:13:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:31 INFO - Downloading blessings-1.6.tar.gz 05:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:13:31 INFO - Installing collected packages: blessings 05:13:31 INFO - Running setup.py install for blessings 05:13:32 INFO - Successfully installed blessings 05:13:32 INFO - Cleaning up... 05:13:32 INFO - Return code: 0 05:13:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:13:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:32 INFO - Reading from file tmpfile_stdout 05:13:32 INFO - Current package versions: 05:13:32 INFO - blessings == 1.6 05:13:32 INFO - blobuploader == 1.2.4 05:13:32 INFO - docopt == 0.6.1 05:13:32 INFO - manifestparser == 1.1 05:13:32 INFO - mozInstall == 1.12 05:13:32 INFO - mozcrash == 0.17 05:13:32 INFO - mozdebug == 0.1 05:13:32 INFO - mozdevice == 0.48 05:13:32 INFO - mozfile == 1.2 05:13:32 INFO - mozhttpd == 0.7 05:13:32 INFO - mozinfo == 0.9 05:13:32 INFO - mozleak == 0.1 05:13:32 INFO - mozlog == 3.1 05:13:32 INFO - moznetwork == 0.27 05:13:32 INFO - mozprocess == 0.22 05:13:32 INFO - mozprofile == 0.28 05:13:32 INFO - mozrunner == 6.11 05:13:32 INFO - mozscreenshot == 0.1 05:13:32 INFO - mozsystemmonitor == 0.0 05:13:32 INFO - moztest == 0.7 05:13:32 INFO - mozversion == 1.4 05:13:32 INFO - psutil == 3.1.1 05:13:32 INFO - requests == 1.2.3 05:13:32 INFO - wsgiref == 0.1.2 05:13:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:32 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 0x1021a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a2cadc20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204bd30>, '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 0x101e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102042b20>, '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.duKc8jXHAU/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.3E16YzOu01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:13:32 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 05:13:32 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 05:13:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duKc8jXHAU/Render', 05:13:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:32 INFO - 'HOME': '/Users/cltbld', 05:13:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:32 INFO - 'LOGNAME': 'cltbld', 05:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:32 INFO - 'MOZ_NO_REMOTE': '1', 05:13:32 INFO - 'NO_EM_RESTART': '1', 05:13:32 INFO - 'PAGER': '/bin/cat', 05:13:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:32 INFO - 'PWD': '/builds/slave/test', 05:13:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:32 INFO - 'SHELL': '/bin/bash', 05:13:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3E16YzOu01/Listeners', 05:13:32 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:13:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:32 INFO - 'USER': 'cltbld', 05:13:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:32 INFO - 'XPC_FLAGS': '0x0', 05:13:32 INFO - 'XPC_SERVICE_NAME': '0', 05:13:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:32 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)) 05:13:32 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)) 05:13:32 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)) 05:13:32 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)) 05:13:32 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)) 05:13:32 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)) 05:13:32 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)) 05:13:32 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)) 05:13:32 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)) 05:13:32 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)) 05:13:32 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)) 05:13:32 INFO - Cleaning up... 05:13:32 INFO - Return code: 0 05:13:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:32 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 0x1021a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a2cadc20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204bd30>, '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 0x101e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102042b20>, '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.duKc8jXHAU/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.3E16YzOu01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:13:32 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 05:13:32 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 05:13:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duKc8jXHAU/Render', 05:13:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:32 INFO - 'HOME': '/Users/cltbld', 05:13:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:32 INFO - 'LOGNAME': 'cltbld', 05:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:32 INFO - 'MOZ_NO_REMOTE': '1', 05:13:32 INFO - 'NO_EM_RESTART': '1', 05:13:32 INFO - 'PAGER': '/bin/cat', 05:13:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:32 INFO - 'PWD': '/builds/slave/test', 05:13:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:32 INFO - 'SHELL': '/bin/bash', 05:13:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3E16YzOu01/Listeners', 05:13:32 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:13:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:32 INFO - 'USER': 'cltbld', 05:13:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:32 INFO - 'XPC_FLAGS': '0x0', 05:13:32 INFO - 'XPC_SERVICE_NAME': '0', 05:13:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:33 INFO - Downloading/unpacking jsonschema 05:13:33 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 05:13:33 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 05:13:33 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 05:13:33 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 05:13:33 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 05:13:33 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 05:13:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:13:35 INFO - Downloading/unpacking functools32 (from jsonschema) 05:13:35 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 05:13:35 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 05:13:35 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 05:13:35 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 05:13:35 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 05:13:35 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 05:13:35 INFO - Downloading functools32-3.2.3-2.tar.gz 05:13:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:13:35 INFO - warning: no files found matching '*.txt' 05:13:35 INFO - no previously-included directories found matching 'build' 05:13:35 INFO - no previously-included directories found matching 'dist' 05:13:35 INFO - no previously-included directories found matching '.git*' 05:13:35 INFO - Installing collected packages: jsonschema, functools32 05:13:35 INFO - Running setup.py install for functools32 05:13:36 INFO - warning: no files found matching '*.txt' 05:13:36 INFO - no previously-included directories found matching 'build' 05:13:36 INFO - no previously-included directories found matching 'dist' 05:13:36 INFO - no previously-included directories found matching '.git*' 05:13:36 INFO - Successfully installed jsonschema functools32 05:13:36 INFO - Cleaning up... 05:13:36 INFO - Return code: 0 05:13:36 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:13:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:36 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 0x1021a7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021ad570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99a2cadc20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10204bd30>, '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 0x101e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102042b20>, '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.duKc8jXHAU/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.3E16YzOu01/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:13:36 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 05:13:36 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 05:13:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duKc8jXHAU/Render', 05:13:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:36 INFO - 'HOME': '/Users/cltbld', 05:13:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:36 INFO - 'LOGNAME': 'cltbld', 05:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:36 INFO - 'MOZ_NO_REMOTE': '1', 05:13:36 INFO - 'NO_EM_RESTART': '1', 05:13:36 INFO - 'PAGER': '/bin/cat', 05:13:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:36 INFO - 'PWD': '/builds/slave/test', 05:13:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:36 INFO - 'SHELL': '/bin/bash', 05:13:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3E16YzOu01/Listeners', 05:13:36 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:13:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:36 INFO - 'USER': 'cltbld', 05:13:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:36 INFO - 'XPC_FLAGS': '0x0', 05:13:36 INFO - 'XPC_SERVICE_NAME': '0', 05:13:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:36 INFO - Downloading/unpacking flake8 05:13:36 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 05:13:36 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 05:13:36 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 05:13:36 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 05:13:36 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 05:13:36 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 05:13:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:13:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:13:39 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 05:13:39 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 05:13:39 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 05:13:39 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 05:13:39 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 05:13:39 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 05:13:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:13:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:13:39 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 05:13:39 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 05:13:39 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 05:13:39 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 05:13:39 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 05:13:39 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 05:13:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:13:40 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 05:13:40 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 05:13:40 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 05:13:40 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 05:13:40 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 05:13:40 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 05:13:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:13:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:13:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:13:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:13:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:13:40 INFO - no previously-included directories found matching 'docs/_build' 05:13:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:13:40 INFO - Running setup.py install for pep8 05:13:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:13:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:13:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:13:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:13:40 INFO - no previously-included directories found matching 'docs/_build' 05:13:40 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:13:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:13:40 INFO - Cleaning up... 05:13:40 INFO - Return code: 0 05:13:40 INFO - Running post-action listener: _resource_record_post_action 05:13:40 INFO - Running post-action listener: _start_resource_monitoring 05:13:41 INFO - Starting resource monitoring. 05:13:41 INFO - ##### 05:13:41 INFO - ##### Finished create-virtualenv step (success) 05:13:41 INFO - ##### 05:13:41 INFO - ##### 05:13:41 INFO - ##### Running install step. 05:13:41 INFO - ##### 05:13:41 INFO - Running pre-action listener: _resource_record_pre_action 05:13:41 INFO - Running main action method: install 05:13:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:41 INFO - Reading from file tmpfile_stdout 05:13:41 INFO - Detecting whether we're running mozinstall >=1.0... 05:13:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:13:41 INFO - Reading from file tmpfile_stdout 05:13:41 INFO - Output received: 05:13:41 INFO - Usage: mozinstall [options] installer 05:13:41 INFO - Options: 05:13:41 INFO - -h, --help show this help message and exit 05:13:41 INFO - -d DEST, --destination=DEST 05:13:41 INFO - Directory to install application into. [default: 05:13:41 INFO - "/builds/slave/test"] 05:13:41 INFO - --app=APP Application being installed. [default: firefox] 05:13:41 INFO - mkdir: /builds/slave/test/build/application 05:13:41 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'] 05:13:41 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 05:14:15 INFO - Reading from file tmpfile_stdout 05:14:15 INFO - Output received: 05:14:15 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:14:15 INFO - Running post-action listener: _resource_record_post_action 05:14:15 INFO - ##### 05:14:15 INFO - ##### Finished install step (success) 05:14:15 INFO - ##### 05:14:15 INFO - ##### 05:14:15 INFO - ##### Running run-tests step. 05:14:15 INFO - ##### 05:14:15 INFO - Running pre-action listener: _resource_record_pre_action 05:14:15 INFO - Running main action method: run_tests 05:14:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:14:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:14:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:14:15 INFO - 2016-05-07 05:14:15.813 screenresolution[1930:11714] starting screenresolution argv=screenresolution get 05:14:15 INFO - 2016-05-07 05:14:15.815 screenresolution[1930:11714] Display 0: 1600x1200x32@60 05:14:15 INFO - 2016-05-07 05:14:15.821 screenresolution[1931:11716] starting screenresolution argv=screenresolution list 05:14:15 INFO - Available Modes on Display 0 05:14:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:14:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:14:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:14:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:14:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:14:16 INFO - Intel Iris: 05:14:16 INFO - Chipset Model: Intel Iris 05:14:16 INFO - Type: GPU 05:14:16 INFO - Bus: Built-In 05:14:16 INFO - VRAM (Dynamic, Max): 1536 MB 05:14:16 INFO - Vendor: Intel (0x8086) 05:14:16 INFO - Device ID: 0x0a2e 05:14:16 INFO - Revision ID: 0x0009 05:14:16 INFO - Displays: 05:14:16 INFO - Display: 05:14:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:14:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:14:16 INFO - Display Serial Number: 1600x1200 60 05:14:16 INFO - Main Display: Yes 05:14:16 INFO - Mirror: Off 05:14:16 INFO - Online: Yes 05:14:16 INFO - Rotation: Supported 05:14:16 INFO - Return code: 0 05:14:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:14:16 INFO - Python 2.7.3 05:14:16 INFO - Return code: 0 05:14:16 INFO - grabbing minidump binary from tooltool 05:14:16 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')]} 05:14:16 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 0x10204bd30>, '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 0x101e6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102042b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:14:16 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 05:14:16 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 05:14:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:14:16 INFO - Return code: 0 05:14:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:14:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:14:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:14:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:14:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:14:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:14:17 INFO - Return code: 0 05:14:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0082 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:14:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.duKc8jXHAU/Render', 05:14:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:17 INFO - 'HOME': '/Users/cltbld', 05:14:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:17 INFO - 'LOGNAME': 'cltbld', 05:14:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:14:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:14:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:17 INFO - 'MOZ_NO_REMOTE': '1', 05:14:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:14:17 INFO - 'NO_EM_RESTART': '1', 05:14:17 INFO - 'PAGER': '/bin/cat', 05:14:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:17 INFO - 'PWD': '/builds/slave/test', 05:14:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:14:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:17 INFO - 'SHELL': '/bin/bash', 05:14:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3E16YzOu01/Listeners', 05:14:17 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:14:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:17 INFO - 'USER': 'cltbld', 05:14:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:17 INFO - 'XPC_FLAGS': '0x0', 05:14:17 INFO - 'XPC_SERVICE_NAME': '0', 05:14:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:14:17 INFO - mozversion application_buildid: 20160507030302 05:14:17 INFO - mozversion application_changeset: 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 05:14:17 INFO - mozversion application_display_name: Nightly 05:14:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:14:17 INFO - mozversion application_name: Firefox 05:14:17 INFO - mozversion application_remotingname: firefox 05:14:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:14:17 INFO - mozversion application_vendor: Mozilla 05:14:17 INFO - mozversion application_version: 49.0a1 05:14:17 INFO - mozversion platform_buildid: 20160507030302 05:14:17 INFO - mozversion platform_changeset: 70dfd4254cf977be2000b41f7a6b97eaada8e2a0 05:14:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:14:17 INFO - mozversion platform_version: 49.0a1 05:14:17 INFO - using testdate: 1462623257 05:14:17 INFO - actual date: 1462623257 05:14:17 INFO - starting webserver on 'localhost:49549' 05:14:17 INFO - SUITE-START | Running 2 tests 05:14:17 INFO - TEST-START | dromaeo_css 05:14:17 INFO - operating with platform_type : mac_ 05:14:17 INFO - Initialising browser for dromaeo_css test... 05:14:17 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpJVu1z2/profile http://localhost:49549/getInfo.html) 05:14:20 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 05:14:20 INFO - PROCESS | 1949 | colorDepth:24 05:14:20 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 05:14:20 INFO - PROCESS | 1949 | __metrics 05:14:20 ERROR - PROCESS | 1949 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:14:21 INFO - TEST-INFO | 1949: exit 0 05:14:21 INFO - Browser initialized. 05:14:21 INFO - Running cycle 1/1 for dromaeo_css test... 05:14:21 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpJVu1z2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:14:33 INFO - PROCESS | 1953 | RSS: Main: 250327040 05:14:33 INFO - PROCESS | 1953 | 05:15:23 INFO - PROCESS | 1953 | 1462623323213 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] 05:17:34 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49549/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49549/tests/dromaeo/cssquery-ext.html) 05:17:36 INFO - PROCESS | 1953 | RSS: Main: 262307840 05:17:36 INFO - PROCESS | 1953 | 05:20:38 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49549/tests/dromaeo/cssquery-ext.html (next: http://localhost:49549/tests/dromaeo/cssquery-jquery.html) 05:20:39 INFO - PROCESS | 1953 | RSS: Main: 274067456 05:20:39 INFO - PROCESS | 1953 | 05:23:41 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49549/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49549/tests/dromaeo/cssquery-mootools.html) 05:23:41 INFO - PROCESS | 1953 | RSS: Main: 276021248 05:23:41 INFO - PROCESS | 1953 | 05:26:43 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49549/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49549/tests/dromaeo/cssquery-prototype.html) 05:26:44 INFO - PROCESS | 1953 | RSS: Main: 277602304 05:26:44 INFO - PROCESS | 1953 | 05:29:46 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49549/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49549/tests/dromaeo/cssquery-yui.html) 05:29:47 INFO - PROCESS | 1953 | RSS: Main: 280510464 05:29:47 INFO - PROCESS | 1953 | 05:32:50 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49549/tests/dromaeo/cssquery-yui.html (next: http://localhost:49549/tests/dromaeo/cssquery-dojo.html) 05:32:50 INFO - PROCESS | 1953 | RSS: Main: 282054656 05:32:50 INFO - PROCESS | 1953 | 05:32:50 INFO - PROCESS | 1953 | __start_tp_report 05:32:50 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 05:32:50 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 05:32:50 INFO - PROCESS | 1953 | |i|pagename|runs| 05:32:50 INFO - PROCESS | 1953 | |0;dojo.html;3485.6862882340592;3499.6150423453414;3530.1527633368;3534.363814513388;3557.039599308187;5846.970765146174;5906.2912450506;5968.7314070866805;5983.461488466037;6001.729922153508;5842.912356314648;5944.138099975501;5988.64068155911;6015.6992150392525;6067.332593414721;5294.364676238837;5311.49540793624;5314;5361.544268737162;5514.200440936062;5310.8406747797435;5459.290292262001;5463.289772329612;5533.916991245134;5542.4457554244655;5222.164453687392;5254.579633629301;5386.676799392049;5396.676199428047;5427.213054107152;3990.7605543667473;4873.195922672755;5349.866253343697;5361.892762144775;5371.892562148735;4882.511748825124;4942.481039490837;5167.7932882684645;5280.471952804727;5297.49673780994;5689.288838895138;5727.140928860705;5808.82573522795;5991.580589358747;6024.036154215349;5305;6232.065190221458;6273.215848018997;6300.905486417708;6334.334894836022;6317.241930968267;6324.3991820776955;6344.809655710336;6356.2054743157105;6363.140975968231;5052.317937078484;5970;6085.0568161934525;6134.141220229082;6137.232845894263;6425.614463132227;6492.610443373413;6494.512911531654;6504.707288172043;6521.380468855451;6179.011358182669;6216.627002379872;6231.470325022424;6246.500279977591;6246.531510136758;6103.91351630514;6256.906146407808;6266.279377871603;6351.523635727339;6390.616563006234;6155.891939450852;6180.042093475518;6208.72060757267;6222.62664240138;6227.844303892349;6115.575014303891;6248.968755156195;6276.435120839146;6281.152044474029;6317.46301564363;5037.823676171316;5047.293378926953;5049.974750126299;5074.213496907985;5100.5409513143995;3834.6357096075826;4507.211238033331;4559.133764584718;4576.633869290449;4599.862004139881;5926.4666180044005;5979.611325263844;6055.152278680988;6078.878422431527;6112.938870611237;6215.9378406215355;6313.747450102036;6321.336259692758;6333.46165575922;6334.5249106317215;6026.276846778327;6035.336113027563;6036.245469316335;6058.303295121029;6067.575269731165;5442.537384322298;5547.8890422192135;5558.805441809517;5569.610127291051;5593.552515798727;6607.57050791697;6611.206655201407;6613.206415230203;6636.469082473391;6640.203175618956;6346.428821406095;6360.427561519486;6365.204349456318;6374.808755737336;6386.648734319471;6336.271328797063;6408.647524386203;6427.389398007182;6465.547411681326;6506.674666266762;6238.376162383725;6246.406591373812;6246.593971391845;6255;6260.248770147611;6342.143810585697;6360.4911607072245;6372.74509019634;6376.266729326187;6378.553501254868;6119.106526814713;6135.508280839814;6143.450468055886;6153.169322141633;6162.506999439945;6183.96908015457;6192.721327540181;6200.224971878515;6207.534434917489;6213.565050446426;6023.879522409439;6036.746709824061;6067.848303792441;6126.172966649446;6240.4071613196675;6141.447269745744;6142.590862071594;6153.538484613761;6186.690665466619;6202.379762023762;5153.922691159561;6178.721957511832;6189.164462797645;6191.721372538156;6241.21984751906 05:32:50 INFO - PROCESS | 1953 | |1;ext.html;484.71159660002303;509.48541972607654;528.5164074871493;541.553218594659;559.1780083277582;35902;40473.59526404735;40508.79745601272;40546.59453405465;40595.39106913395;41026.7948660257;41582.58417415825;41630.58369416305;41692.79153604235;41820.372694409605;9580;9759.072888075621;9767.658131965383;9780.462074585896;9782.21742260621;9573.282003849705;9601.711948641518;9601.759956001086;9656.468894210815;9700.902990970106;9595.280353973445;9650.276229282797;9677.56450959705;9723.319367644268;9738.464384458852;9653.565589548487;9680.806383872354;9693;9715.319927605035;9740.074692904163;9509.429434233969;9515.286353523514;9589.328746987643;9606.90393096067;9631.422114673142;37670.81164594187;38050;38168.23663526714;38375;38409.42385864214;3151.7163455289133;3165.398574270881;3321.667833216683;3356.4461863792444;3425.937959232621;27369.45261094767;27975;28581.99963001285;29125.980590679224;29170;2481.789047930928;2547.3886267295716;2579;2581.728918463572;2780.624615676878;528943;529383.353083236;537554;698501;699463;34970;64006.67996659987;64301.67849160724;64561.67719161468;65141;30559.847200763856;36343;36781.63218367835;36878.26243475169;36878.893633191045;15495.070295782289;15497.5350739478;15525.689486210213;15532.611684707972;15590.610234743996;12722.236665800081;13079.934600326937;13114.213147211198;13148;13151.802722959163;7877.645505952246;8197.754067377988;8219.917800821915;8234.547099909563;8262.917370826335;3241.6596257393107;3242.1570391697855;3407.897763067112;3417.624061353249;3427.7086447652227;966.4104896013426;973.5424350555228;1061.883192848786;1076.284270959803;1104.5084937202867;28593;29085;29187.124386268446;29568.852155739514;29843.552346714816;992.6228033347281;1061.543536279392;1066.7759770448138;1116.6203490813164;1186.1400484648734;15128;15157.469488568067;15162;15403.922980385027;15431.228438578251;682919;687067;688464;688676.5566172238;692753;59091.52271193254;59613;59836.401635983086;59873;60081.399186007584;1110.4503270880966;1352.8985326100583;1371.7599420101642;1422.0969684250576;1490.6422458609857;28860.7113928858;28892.711072889004;29022;29140.70859291465;29314.56028159622;51199.48800511947;51482;51639.48360516497;51657.225141623654;51780.22329664983;561052;573500;578774;578863;579622;26660.733392665825;27067.86466067736;27093.59359609568;27113.45773084567;27173;45172;45278.773606131756;45554.811163218255;46261;46411.83970400767;37211.6278837219;37395.62604373921;37520.81239593784;37827.62172378351;38093.80953095217;36143.096422589646;36847.63152368442;37015.81492092522;37301;37522.061948451505;36036.4594531077;37154;37258;38369;39991;29287.12138635844;32566.674333256364;32873.506897397034;33429;33994.66005339915;20469.590608187456;21971.450713732283;22533.211337603105;23223.883880580488;24750.752492475563 05:32:50 INFO - PROCESS | 1953 | |2;jquery.html;369.17489411166036;375.4424679351163;387.53689341237214;391.794307988306;403.8041549848321;11675.241109327888;18812.717809232854;20944.581108377824;21221.68167477487;21231;20574.765514069168;21376;22060.558788824215;22837.543249135048;23170.88414557925;5900.868595107168;6637.469002479802;6817.556858804187;6837.965810170942;6874.725010999579;6789;6850.280720524348;6863.588184708909;6878.484113691468;7079.398251148658;7350.301721336491;7497.400207983348;7557.622118894061;7584.582847943361;7596.810079748023;7739.729109481169;7758.650860711282;7776.883346749803;7789.649465774026;7806.33646140076;7138.357547820721;7413.481056326059;7442.962785186093;7490;7515.699372025112;28121.875124995182;28237.822707620107;28824.27939301513;29584.70415295841;29751.661175247165;2225.877576733279;2318.9188378406675;2389.163792672568;2420.891059902309;2544.9236522904343;13460.192388456626;16151.596210094842;16370.590735231595;16521.586960326087;16946.3221471141;1828.9725654115198;1848.6857234270205;1904.7333373327745;1936.1868015433552;1938.4378530226218;116252;122068.38965805114;138986.30506847502;139881;142672.8599071015;16542.42101526441;21347.46631334229;21358.679619805716;21470.677939830915;21852.89073554622;35110;35640.28719425597;35779.10552236215;36077.639223607424;36097.5560977558;9770.12068913787;11147.777044459066;11183.496742646601;11342.489587968395;11365;7670.8082297943;7868.606569671493;9240.537973101318;9256.95371523138;9389.295802814817;9650.628868037504;9765.853512197322;9781.755456113655;9912.306138570231;9970.501474926225;3765.661090501868;3856.3829787234467;3891.20230352777;3981.2775055323364;4003.2393845169327;391.7864763703788;405.5863019719869;421.0001247038284;438.7784168994637;857.9270761985301;11606.187566870238;11618;11853.88146118545;12003.21979071375;12111.939440302918;435.87978894241496;447.25085481817825;447.6910931457284;454.9317602359606;469.5351601914149;5959.612625179349;6064.090386442069;6151.792030768921;6165.938340616626;6170.10533472644;589691;601807;604511;606722;609161.9541902351;120575;122401;135087.32456337655;137257;144267;446.73865788513433;462.0873774295739;463.16167736396557;463.6568938985127;464.2780476359294;8393.256750708784;10835.428862815006;10936.507857146607;11075.418229267856;11177.497012634614;47196;49168.2624760636;50274.74862625664;50328.49671503238;50339.74830125826;68875;73629;74320;74550;74934;7876.34006482174;8062.475939063942;8090.312323452337;8261.876071859047;8304.543250121302;8989.955050224708;11567.942160289145;12327.383630818602;12743.235405875677;12879.871201288242;10313.690589282334;10393.58425662965;10478.37129772216;10511.316764410289;10722.463876805972;10621.097206737513;10780.407077610807;10914.672559823219;11035.503402347074;11101.888981110085;10402.47987600632;10487.213458990454;10705.678829635124;10820.621278255223;10907.454627268764;6949.7567585134275;8175.632096555788;8226.424150309422;8291.58542072906;8416.116307787594;9215.585298661443;10126.544305506122;10470.528826202686;10554.10290125348;10726.892731072589 05:32:50 INFO - PROCESS | 1953 | |3;mootools.html;1564.507180238225;1589.237166160243;1848.8428483578896;1938.0309845077452;1945.727598136262;7950.960245198766;8287.046989596198;8520.36097292704;8798.604062817181;8800.383973121883;7470.813229669261;7947.284744373005;8392.076871544125;8693.521856297917;8870.911290887105;2125.90433430496;2447.302518782147;2486.875656217182;2495.488424872906;2497.2383423056053;2351.2123438648073;2438.1588352018525;2481.4540801023836;2491.613799861015;2496.1887386599337;2113.4505028692574;2465.149523414419;2467.3214865911837;2489.6638953741194;2496.3384703053707;2434.549608322457;2445.63315502676;2449.9020039198413;2451.7180524239675;2462.0274991378483;2443.596806526921;2458.7172475165407;2458.938526536833;2466.48203877186;2471.505698860235;8760.8685869712;8853;8868.778280543038;8889.911100889038;8933.776655583662;5371.516563509303;5483.862903427446;5545.639533430314;5548.889022219573;5550.888982220333;3483.8432270547887;3522.3307571561577;3539.1152211947015;3576.7138628909624;3583.372909740785;881.3478026260585;1002.4536627538009;1014.1329163565119;1015.0255754475712;1018.8471729240599;11364.374959377232;11368;11487.827682584768;11505.5973040944;11609.30344179352;4207.621314081747;4576.336431217454;4753.952460475421;4822.445418776885;4895.975520122377;9923.503824808875;9947.403155810674;9953.950230248947;9983.251256155818;10021.549030293654;5322.2282768998275;5334.67991920486;5359.633293510208;5429.321334833146;5462.098753705594;4371.388005679208;4738.668293219511;4942.085714142895;4955.851324460272;4959.603231741452;3639.94540081899;4000.0599859033105;4022.7988600569856;4034.636882680573;4177.665786737054;1341.403075631354;1351.3918736568583;1354.3025341948903;1358.65354334646;1360.3674291454522;868.8653258744905;913.7395842184945;924.9722508324761;940.4874343482818;958.0885933788403;5971.940280597225;6042.12389203563;6092.72582733778;6155.353687862799;6183.763247350548;454.9226631472658;470.54827365729284;472.0794450820913;472.3505180376982;473.1908436573429;3965.5439624442984;4024.4969378827645;4039.5758445363404;4086.6322031017353;4102.261592913244;10296.639617613348;10825.83761243582;10852.837207441895;10854.511546980404;10886.237963342648;10657.946710266398;10717.46412679363;10773.976472235125;10827.404492752974;10830.945845270724;7891.763247102481;8128.146544612849;8244.258016778518;8270.710525131712;8282.544460054754;5160.974195129001;5847.912281315698;5896.734646940812;5965.970170149121;5991.580589358878;9744.366616169926;9761.365511241747;9832.065953734384;9855.901440985499;9910.968251508568;9779.315447918578;10159.238057145596;10248.48757562104;10275.486225688535;10370.948145259226;6157.815265541862;6949.200841903245;7500.212477689982;7538.509996850187;7594.202608726005;8404.705835295736;8428.325733941392;8501.617427215666;8502.829943400973;8533.786655333666;7041.295870412918;7053.153621565445;7339;7507.66215520292;7590.203028681909;7094.219635840157;7351.191368949519;7419.220981796833;7488;7513.211112833293;5894.941050589611;7035.0150978861975;7037.894431583428;7054.29457054311;7057.258987806206;4897.363342765418;4909.643371181782;5039.067772462106;5082.771275292695;5219.869503262449;4992.8751781205765;5067.366579177603;5095.108356037753;5114.514121158484;5306.151015837448 05:32:50 INFO - PROCESS | 1953 | |4;prototype.html;304.2196765297012;312.7529251891006;318.63516273866423;322.81760805145075;324.5650827890626;4807.44714357849;4910.75446227689;5291.518374855047;5501.349595121463;5516.172574113885;4995.4505004449575;5411.567074634029;5434.445810469073;5465.699386533739;5473.534749546293;4118.361653943643;4122.732022418548;4128.502256135025;4137.958620413788;4142.647874930634;3784.432335149722;3937.389704595778;4125.855595054174;4162.542120366757;4220.873373798791;4014.2172276406113;4039.4546736190678;4066.715329926906;4073.103917138225;4083.8774836754797;3444.2078321986082;4061.6344528992236;4079.6940229482757;4094.795260237006;4119.79590918857;3372.595288565388;3535.1338921964284;3536.8054756988354;3548.8048157351336;3550.3254381667402;4161.91676166478;5107.080725469414;5181.715005674724;5336.599755018389;5345.572354211654;3938.448617193595;4664.230401983668;4696.906061878777;4710.952890471086;4731.787069581877;5216.556592689626;5733.799317023884;5767.711614419262;5771.797987070474;5779.35288442796;3327.88352407667;3795.62043795621;3913.2390424309174;3924.3917192835156;3925.88222353327;5418.512333889968;5956.761729530814;5991.820245392645;6002.189704389939;6113.602615829956;3817.7401457518954;3843.942340864889;3917.0990672145567;3969.821358038895;3970.265500882346;3113.875444982221;3629.673329400314;3826.445165450994;3997.5003124609425;4012.117334186474;3670.8708948809626;4528.705634133771;4609.7695115244105;4646.744429056367;4704.153252414599;5309.707966061904;5394.78420863169;5486.506214440639;5777.931082749705;5835.824925252249;1175.9353235572125;1244.122893360179;1270.917390369623;1289.3875409180564;1309.3976770685592;713.5540287320425;852.0669866496177;861.9439736417238;889.0664801957919;891.8483857744199;4482.744831447135;4512.939459227079;4743.169945259566;5058.620603454756;5104.259882317117;4891.706497610084;5022.146235140035;5022.723750193702;5564.4991950724625;5664.895345407606;4326.978365108217;4933.889874778146;5057.974710126426;5100.464451232567;5105.668131571436;2638.0898589986423;2991.4145502883507;2995.6854530274445;3008.7292143707173;3036.6963303669454;5602;5655.773769049192;5899.4985426239255;5963.541146135231;6130.517140558081;4951.8115652243205;5220.295260139833;5576.581756368289;5583.832485025456;5839.912401313899;5205.895882082337;5290.761915713802;5873.765049397976;5981.252343457068;6014.248218972629;4758.905451746048;5478.698671573102;5490.560755139659;5746.626469279484;5801.303843538633;4691.835785747619;5028.21498368074;5051.545360917535;5221.634485586124;5283.656562323436;5375.731213439391;5494.2582751328055;5608.775648974159;5965.791197308074;5971.4327138921735;3441.8107004115013;3597.76580731074;3663.468797024363;3693.409054551259;3819.4843696101784;4334.133173365402;5248.580113590827;5429.511343979061;5663.96977616973;5687.3459552152035;4958.479359667327;5546.389897111234;5708.600397972102;5731.541476681772;5749.338826035059;4700.7179569225955;5231.764570594256;5319.005372985131;5681.0058239808695;5722.284714410699;5160.432352441153;5330.786768529216;5534.280543529316;5556.305461817273;5570.442955704397;4929.605631549396;5691.77232910679;5751.8562035949435;5754.597178197486;5788.53691704671;4567.406237189144;5400.459954004568;5571.777128914798;5609.80365687199;5611.242482264842 05:32:50 INFO - PROCESS | 1953 | |5;yui.html;641.3810672700843;731.8755811512042;747.2228881962756;766.1113108793799;801.2588355770911;14187.503437379693;15314.846851531482;15396.769048464283;15456.304466299016;15495.91528592999;13963;13964.930175349135;14056.875449964004;14133.78799318011;14139;711.3242419701304;869.5869462005528;890.8441022821013;908.1372695938885;908.318760929303;866.33292364879;869.0310304011014;895.6372669069037;896.3994123936978;903.1284810158196;937.7046230437425;956.6603855631255;957.4590356448584;957.5355952363099;959.27574681116;922.9123233292827;923.0215971070686;944.2493217891792;945.508335665457;955.4458414119794;691.498663468986;701.2076353720314;708.327089265195;711.1040089487232;712.5261700968849;14667.706645867129;14970.925145374204;15024.924875375553;15031;15041.623959400993;12534.185277956904;12576.308303043328;12619.684507887285;12672.619821405373;13099;9830.361026533325;10127.696169114939;10156.441395723232;10201.030902064293;10405.219608529314;3454.29187016661;3472.1146107742616;3494.2662040971427;3496.30073985204;3535.7701749386465;87774.5611271946;89612;92391.07608923892;92414.53792731061;93248.53375733146;8943.552822358855;9137.588808503633;9546;9593.712188634352;9717.854232186522;19931.00344982745;20458.488537786674;20470.078846451946;20754.58490830175;20756.68864967027;7086.858262834715;7310.671019804122;7559.1684914659345;7785.844283114419;7822.217778222246;4027.8993025174605;4045.838166473367;4053.959460405358;4077.734947228421;4093.038136038029;3436.759426840097;3559.1458050068313;3563.8574457021946;3583.5878873929832;3587.6412358763914;642.0722056628127;645.4062262718311;655.0796131435341;658.4140115297389;659.2978477920999;556.5789213064801;567.3503838105346;577.3908526504578;578.320473443702;599.7061439894508;7691.153973062958;7835.6473958671995;7916.881246781303;8053.952986111768;8088.919110808934;548.0846985534167;562.353293712226;577.7515668262687;588.6468119128489;593.1725243285679;3662.505561749183;3669.046048027532;3757.248550289953;3776.811159442072;3847.711421643388;58001;75821.24178758252;88526.11473885308;88720.5563972176;90011.54994224987;59935;77457.225427745;77492.61253693695;78473.2152678466;80767;58748.4125158743;71876;71975;72888.63555682293;73337.6333118331;7483.550986940801;7519.661615227437;7567.129780075142;7634.580098094658;7684.731034413769;32744;34561;42290.36564451474;45545.089098218516;45577.54422455733;61189.470263241994;62113.378866212566;62523.43691407751;62961.42596434942;63056.68471657612;8563.486190828571;9882.901170988198;9894.604215831574;9916.107550320507;10405.687829365132;10689.251752377259;10797.730056748644;11041.944790275997;11086.889131108908;11234.719132021439;9557.713268601952;9574.808503830023;9805.460699661588;10039.69880903574;10401.53193106297;10049.045340692623;10064.39613623185;10351.534180962026;10392.272540922062;10455.634052808115;9526.52367381642;9576.186024188022;9656.468894210886;9887.209023277977;10247.128994035591;8466.163553136212;8680.479171249744;8683.82632347337;8757.781055473664;8905.955470222607;8071.919280807117;8393.580320983805;8660.610272537877;8804.911950880409;9054.185123338932 05:32:50 INFO - PROCESS | 1953 | __end_tp_report 05:32:50 INFO - PROCESS | 1953 | __start_cc_report 05:32:50 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,426 05:32:50 INFO - PROCESS | 1953 | __end_cc_report 05:32:50 INFO - PROCESS | 1953 | __startTimestamp1462624370671__endTimestamp 05:32:50 INFO - PROCESS | 1953 | 05:32:50 INFO - PROCESS | 1953 | ------- Summary: start ------- 05:32:50 INFO - PROCESS | 1953 | Number of tests: 6 05:32:50 INFO - PROCESS | 1953 | 05:32:50 INFO - PROCESS | 1953 | [#0] dojo.html Cycles:165 Average:5857.97 Median:6134.14 stddev:670.33 (10.9%) stddev-sans-first:646.03 05:32:50 INFO - PROCESS | 1953 | Values: 3485.7 3499.6 3530.2 3534.4 3557.0 5847.0 5906.3 5968.7 5983.5 6001.7 5842.9 5944.1 5988.6 6015.7 6067.3 5294.4 5311.5 5314.0 5361.5 5514.2 5310.8 5459.3 5463.3 5533.9 5542.4 5222.2 5254.6 5386.7 5396.7 5427.2 3990.8 4873.2 5349.9 5361.9 5371.9 4882.5 4942.5 5167.8 5280.5 5297.5 5689.3 5727.1 5808.8 5991.6 6024.0 5305.0 6232.1 6273.2 6300.9 6334.3 6317.2 6324.4 6344.8 6356.2 6363.1 5052.3 5970.0 6085.1 6134.1 6137.2 6425.6 6492.6 6494.5 6504.7 6521.4 6179.0 6216.6 6231.5 6246.5 6246.5 6103.9 6256.9 6266.3 6351.5 6390.6 6155.9 6180.0 6208.7 6222.6 6227.8 6115.6 6249.0 6276.4 6281.2 6317.5 5037.8 5047.3 5050.0 5074.2 5100.5 3834.6 4507.2 4559.1 4576.6 4599.9 5926.5 5979.6 6055.2 6078.9 6112.9 6215.9 6313.7 6321.3 6333.5 6334.5 6026.3 6035.3 6036.2 6058.3 6067.6 5442.5 5547.9 5558.8 5569.6 5593.6 6607.6 6611.2 6613.2 6636.5 6640.2 6346.4 6360.4 6365.2 6374.8 6386.6 6336.3 6408.6 6427.4 6465.5 6506.7 6238.4 6246.4 6246.6 6255.0 6260.2 6342.1 6360.5 6372.7 6376.3 6378.6 6119.1 6135.5 6143.5 6153.2 6162.5 6184.0 6192.7 6200.2 6207.5 6213.6 6023.9 6036.7 6067.8 6126.2 6240.4 6141.4 6142.6 6153.5 6186.7 6202.4 5153.9 6178.7 6189.2 6191.7 6241.2 05:32:50 INFO - PROCESS | 1953 | 05:32:50 INFO - PROCESS | 1953 | [#1] ext.html Cycles:180 Average:72947.40 Median:37749.22 stddev:167553.38 (443.9%) stddev-sans-first:167935.08 05:32:50 INFO - PROCESS | 1953 | Values: 484.7 509.5 528.5 541.6 559.2 35902.0 40473.6 40508.8 40546.6 40595.4 41026.8 41582.6 41630.6 41692.8 41820.4 9580.0 9759.1 9767.7 9780.5 9782.2 9573.3 9601.7 9601.8 9656.5 9700.9 9595.3 9650.3 9677.6 9723.3 9738.5 9653.6 9680.8 9693.0 9715.3 9740.1 9509.4 9515.3 9589.3 9606.9 9631.4 37670.8 38050.0 38168.2 38375.0 38409.4 3151.7 3165.4 3321.7 3356.4 3425.9 27369.5 27975.0 28582.0 29126.0 29170.0 2481.8 2547.4 2579.0 2581.7 2780.6 528943.0 529383.4 537554.0 698501.0 699463.0 34970.0 64006.7 64301.7 64561.7 65141.0 30559.8 36343.0 36781.6 36878.3 36878.9 15495.1 15497.5 15525.7 15532.6 15590.6 12722.2 13079.9 13114.2 13148.0 13151.8 7877.6 8197.8 8219.9 8234.5 8262.9 3241.7 3242.2 3407.9 3417.6 3427.7 966.4 973.5 1061.9 1076.3 1104.5 28593.0 29085.0 29187.1 29568.9 29843.6 992.6 1061.5 1066.8 1116.6 1186.1 15128.0 15157.5 15162.0 15403.9 15431.2 682919.0 687067.0 688464.0 688676.6 692753.0 59091.5 59613.0 59836.4 59873.0 60081.4 1110.5 1352.9 1371.8 1422.1 1490.6 28860.7 28892.7 29022.0 29140.7 29314.6 51199.5 51482.0 51639.5 51657.2 51780.2 561052.0 573500.0 578774.0 578863.0 579622.0 26660.7 27067.9 27093.6 27113.5 27173.0 45172.0 45278.8 45554.8 46261.0 46411.8 37211.6 37395.6 37520.8 37827.6 38093.8 36143.1 36847.6 37015.8 37301.0 37522.1 36036.5 37154.0 37258.0 38369.0 39991.0 29287.1 32566.7 32873.5 33429.0 33994.7 20469.6 21971.5 22533.2 23223.9 24750.8 05:32:50 INFO - PROCESS | 1953 | 05:32:50 INFO - PROCESS | 1953 | [#2] jquery.html Cycles:180 Average:36249.12 Median:2139.37 stddev:100788.39 (4711.1%) stddev-sans-first:101035.12 05:32:50 INFO - PROCESS | 1953 | Values: 369.2 375.4 387.5 391.8 403.8 11675.2 18812.7 20944.6 21221.7 21231.0 20574.8 21376.0 22060.6 22837.5 23170.9 5900.9 6637.5 6817.6 6838.0 6874.7 6789.0 6850.3 6863.6 6878.5 7079.4 7350.3 7497.4 7557.6 7584.6 7596.8 7739.7 7758.7 7776.9 7789.6 7806.3 7138.4 7413.5 7443.0 7490.0 7515.7 28121.9 28237.8 28824.3 29584.7 29751.7 2225.9 2318.9 2389.2 2420.9 2544.9 13460.2 16151.6 16370.6 16521.6 16946.3 1829.0 1848.7 1904.7 1936.2 1938.4 116252.0 122068.4 138986.3 139881.0 142672.9 16542.4 21347.5 21358.7 21470.7 21852.9 35110.0 35640.3 35779.1 36077.6 36097.6 9770.1 11147.8 11183.5 11342.5 11365.0 7670.8 7868.6 9240.5 9257.0 9389.3 9650.6 9765.9 9781.8 9912.3 9970.5 3765.7 3856.4 3891.2 3981.3 4003.2 391.8 405.6 421.0 438.8 857.9 11606.2 11618.0 11853.9 12003.2 12111.9 435.9 447.3 447.7 454.9 469.5 5959.6 6064.1 6151.8 6165.9 6170.1 589691.0 601807.0 604511.0 606722.0 609162.0 120575.0 122401.0 135087.3 137257.0 144267.0 446.7 462.1 463.2 463.7 464.3 8393.3 10835.4 10936.5 11075.4 11177.5 47196.0 49168.3 50274.7 50328.5 50339.7 68875.0 73629.0 74320.0 74550.0 74934.0 7876.3 8062.5 8090.3 8261.9 8304.5 8990.0 11567.9 12327.4 12743.2 12879.9 10313.7 10393.6 10478.4 10511.3 10722.5 10621.1 10780.4 10914.7 11035.5 11101.9 10402.5 10487.2 10705.7 10820.6 10907.5 6949.8 8175.6 8226.4 8291.6 8416.1 9215.6 10126.5 10470.5 10554.1 10726.9 05:32:50 INFO - PROCESS | 1953 | 05:32:50 INFO - PROCESS | 1953 | [#3] mootools.html Cycles:180 Average:5705.31 Median:4976.24 stddev:3175.78 (63.8%) stddev-sans-first:3169.44 05:32:50 INFO - PROCESS | 1953 | Values: 1564.5 1589.2 1848.8 1938.0 1945.7 7951.0 8287.0 8520.4 8798.6 8800.4 7470.8 7947.3 8392.1 8693.5 8870.9 2125.9 2447.3 2486.9 2495.5 2497.2 2351.2 2438.2 2481.5 2491.6 2496.2 2113.5 2465.1 2467.3 2489.7 2496.3 2434.5 2445.6 2449.9 2451.7 2462.0 2443.6 2458.7 2458.9 2466.5 2471.5 8760.9 8853.0 8868.8 8889.9 8933.8 5371.5 5483.9 5545.6 5548.9 5550.9 3483.8 3522.3 3539.1 3576.7 3583.4 881.3 1002.5 1014.1 1015.0 1018.8 11364.4 11368.0 11487.8 11505.6 11609.3 4207.6 4576.3 4754.0 4822.4 4896.0 9923.5 9947.4 9954.0 9983.3 10021.5 5322.2 5334.7 5359.6 5429.3 5462.1 4371.4 4738.7 4942.1 4955.9 4959.6 3639.9 4000.1 4022.8 4034.6 4177.7 1341.4 1351.4 1354.3 1358.7 1360.4 868.9 913.7 925.0 940.5 958.1 5971.9 6042.1 6092.7 6155.4 6183.8 454.9 470.5 472.1 472.4 473.2 3965.5 4024.5 4039.6 4086.6 4102.3 10296.6 10825.8 10852.8 10854.5 10886.2 10657.9 10717.5 10774.0 10827.4 10830.9 7891.8 8128.1 8244.3 8270.7 8282.5 5161.0 5847.9 5896.7 5966.0 5991.6 9744.4 9761.4 9832.1 9855.9 9911.0 9779.3 10159.2 10248.5 10275.5 10370.9 6157.8 6949.2 7500.2 7538.5 7594.2 8404.7 8428.3 8501.6 8502.8 8533.8 7041.3 7053.2 7339.0 7507.7 7590.2 7094.2 7351.2 7419.2 7488.0 7513.2 5894.9 7035.0 7037.9 7054.3 7057.3 4897.4 4909.6 5039.1 5082.8 5219.9 4992.9 5067.4 5095.1 5114.5 5306.2 05:32:50 INFO - PROCESS | 1953 | 05:32:50 INFO - PROCESS | 1953 | [#4] prototype.html Cycles:180 Average:4509.72 Median:5039.88 stddev:1387.82 (27.5%) stddev-sans-first:1355.35 05:32:50 INFO - PROCESS | 1953 | Values: 304.2 312.8 318.6 322.8 324.6 4807.4 4910.8 5291.5 5501.3 5516.2 4995.5 5411.6 5434.4 5465.7 5473.5 4118.4 4122.7 4128.5 4138.0 4142.6 3784.4 3937.4 4125.9 4162.5 4220.9 4014.2 4039.5 4066.7 4073.1 4083.9 3444.2 4061.6 4079.7 4094.8 4119.8 3372.6 3535.1 3536.8 3548.8 3550.3 4161.9 5107.1 5181.7 5336.6 5345.6 3938.4 4664.2 4696.9 4711.0 4731.8 5216.6 5733.8 5767.7 5771.8 5779.4 3327.9 3795.6 3913.2 3924.4 3925.9 5418.5 5956.8 5991.8 6002.2 6113.6 3817.7 3843.9 3917.1 3969.8 3970.3 3113.9 3629.7 3826.4 3997.5 4012.1 3670.9 4528.7 4609.8 4646.7 4704.2 5309.7 5394.8 5486.5 5777.9 5835.8 1175.9 1244.1 1270.9 1289.4 1309.4 713.6 852.1 861.9 889.1 891.8 4482.7 4512.9 4743.2 5058.6 5104.3 4891.7 5022.1 5022.7 5564.5 5664.9 4327.0 4933.9 5058.0 5100.5 5105.7 2638.1 2991.4 2995.7 3008.7 3036.7 5602.0 5655.8 5899.5 5963.5 6130.5 4951.8 5220.3 5576.6 5583.8 5839.9 5205.9 5290.8 5873.8 5981.3 6014.2 4758.9 5478.7 5490.6 5746.6 5801.3 4691.8 5028.2 5051.5 5221.6 5283.7 5375.7 5494.3 5608.8 5965.8 5971.4 3441.8 3597.8 3663.5 3693.4 3819.5 4334.1 5248.6 5429.5 5664.0 5687.3 4958.5 5546.4 5708.6 5731.5 5749.3 4700.7 5231.8 5319.0 5681.0 5722.3 5160.4 5330.8 5534.3 5556.3 5570.4 4929.6 5691.8 5751.9 5754.6 5788.5 4567.4 5400.5 5571.8 5609.8 5611.2 05:32:50 INFO - PROCESS | 1953 | 05:32:50 INFO - PROCESS | 1953 | [#5] yui.html Cycles:180 Average:17442.37 Median:658.86 stddev:24982.07 (3791.7%) stddev-sans-first:25020.30 05:32:50 INFO - PROCESS | 1953 | Values: 641.4 731.9 747.2 766.1 801.3 14187.5 15314.8 15396.8 15456.3 15495.9 13963.0 13964.9 14056.9 14133.8 14139.0 711.3 869.6 890.8 908.1 908.3 866.3 869.0 895.6 896.4 903.1 937.7 956.7 957.5 957.5 959.3 922.9 923.0 944.2 945.5 955.4 691.5 701.2 708.3 711.1 712.5 14667.7 14970.9 15024.9 15031.0 15041.6 12534.2 12576.3 12619.7 12672.6 13099.0 9830.4 10127.7 10156.4 10201.0 10405.2 3454.3 3472.1 3494.3 3496.3 3535.8 87774.6 89612.0 92391.1 92414.5 93248.5 8943.6 9137.6 9546.0 9593.7 9717.9 19931.0 20458.5 20470.1 20754.6 20756.7 7086.9 7310.7 7559.2 7785.8 7822.2 4027.9 4045.8 4054.0 4077.7 4093.0 3436.8 3559.1 3563.9 3583.6 3587.6 642.1 645.4 655.1 658.4 659.3 556.6 567.4 577.4 578.3 599.7 7691.2 7835.6 7916.9 8054.0 8088.9 548.1 562.4 577.8 588.6 593.2 3662.5 3669.0 3757.2 3776.8 3847.7 58001.0 75821.2 88526.1 88720.6 90011.5 59935.0 77457.2 77492.6 78473.2 80767.0 58748.4 71876.0 71975.0 72888.6 73337.6 7483.6 7519.7 7567.1 7634.6 7684.7 32744.0 34561.0 42290.4 45545.1 45577.5 61189.5 62113.4 62523.4 62961.4 63056.7 8563.5 9882.9 9894.6 9916.1 10405.7 10689.3 10797.7 11041.9 11086.9 11234.7 9557.7 9574.8 9805.5 10039.7 10401.5 10049.0 10064.4 10351.5 10392.3 10455.6 9526.5 9576.2 9656.5 9887.2 10247.1 8466.2 8680.5 8683.8 8757.8 8906.0 8071.9 8393.6 8660.6 8804.9 9054.2 05:32:50 INFO - PROCESS | 1953 | -------- Summary: end -------- 05:32:50 INFO - PROCESS | 1953 | 05:32:51 INFO - TEST-INFO | 1953: exit 0 05:32:51 INFO - TEST-OK | dromaeo_css | took 1113755ms 05:32:51 INFO - TEST-START | kraken 05:32:51 INFO - operating with platform_type : mac_ 05:32:51 INFO - Initialising browser for kraken test... 05:32:51 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpWtCa7K/profile http://localhost:49549/getInfo.html) 05:32:54 INFO - PROCESS | 1960 | __metrics Screen width/height:1600/1200 05:32:54 INFO - PROCESS | 1960 | colorDepth:24 05:32:54 INFO - PROCESS | 1960 | Browser inner width/height: 1024/689 05:32:54 INFO - PROCESS | 1960 | __metrics 05:32:54 ERROR - PROCESS | 1960 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:32:54 INFO - TEST-INFO | 1960: exit 0 05:32:54 INFO - Browser initialized. 05:32:54 INFO - Running cycle 1/1 for kraken test... 05:32:54 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpWtCa7K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:06 INFO - PROCESS | 1964 | RSS: Main: 250363904 05:33:06 INFO - PROCESS | 1964 | 05:33:09 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49549/tests/kraken/driver.html?testName=audio-beat-detection) 05:33:10 INFO - PROCESS | 1964 | RSS: Main: 262131712 05:33:10 INFO - PROCESS | 1964 | 05:33:13 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49549/tests/kraken/driver.html?testName=audio-dft) 05:33:14 INFO - PROCESS | 1964 | RSS: Main: 267337728 05:33:14 INFO - PROCESS | 1964 | 05:33:17 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49549/tests/kraken/driver.html?testName=audio-fft) 05:33:17 INFO - PROCESS | 1964 | RSS: Main: 267206656 05:33:17 INFO - PROCESS | 1964 | 05:33:19 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49549/tests/kraken/driver.html?testName=audio-oscillator) 05:33:20 INFO - PROCESS | 1964 | RSS: Main: 268877824 05:33:20 INFO - PROCESS | 1964 | 05:33:22 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49549/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:33:23 INFO - PROCESS | 1964 | RSS: Main: 268800000 05:33:23 INFO - PROCESS | 1964 | 05:33:26 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49549/tests/kraken/driver.html?testName=imaging-darkroom) 05:33:27 INFO - PROCESS | 1964 | RSS: Main: 268992512 05:33:27 INFO - PROCESS | 1964 | 05:33:29 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49549/tests/kraken/driver.html?testName=imaging-desaturate) 05:33:30 INFO - PROCESS | 1964 | RSS: Main: 269348864 05:33:30 INFO - PROCESS | 1964 | 05:33:33 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49549/tests/kraken/driver.html?testName=json-parse-financial) 05:33:34 INFO - PROCESS | 1964 | RSS: Main: 269512704 05:33:34 INFO - PROCESS | 1964 | 05:33:35 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49549/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:33:36 INFO - PROCESS | 1964 | RSS: Main: 268439552 05:33:36 INFO - PROCESS | 1964 | 05:33:38 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49549/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:33:38 INFO - PROCESS | 1964 | RSS: Main: 268505088 05:33:38 INFO - PROCESS | 1964 | 05:33:41 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49549/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:33:41 INFO - PROCESS | 1964 | RSS: Main: 268996608 05:33:41 INFO - PROCESS | 1964 | 05:33:44 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49549/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:33:45 INFO - PROCESS | 1964 | RSS: Main: 269086720 05:33:45 INFO - PROCESS | 1964 | 05:33:48 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49549/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:33:48 INFO - PROCESS | 1964 | RSS: Main: 269307904 05:33:48 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:49549/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49549/tests/kraken/driver.html?testName=ai-astar) 05:33:50 INFO - PROCESS | 1964 | RSS: Main: 270327808 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | __start_tp_report 05:33:50 INFO - PROCESS | 1964 | _x_x_mozilla_page_load 05:33:50 INFO - PROCESS | 1964 | _x_x_mozilla_page_load_details 05:33:50 INFO - PROCESS | 1964 | |i|pagename|runs| 05:33:50 INFO - PROCESS | 1964 | |0;ai-astar;112;118;116;169;164;118;109;117;117;116 05:33:50 INFO - PROCESS | 1964 | |1;audio-beat-detection;139;119;146;159;114;169;161;126;196;113 05:33:50 INFO - PROCESS | 1964 | |2;audio-dft;195;215;233;204;202;196;206;203;195;201 05:33:50 INFO - PROCESS | 1964 | |3;audio-fft;68;80;74;158;74;57;59;62;130;60 05:33:50 INFO - PROCESS | 1964 | |4;audio-oscillator;79;139;168;105;81;86;126;82;84;122 05:33:50 INFO - PROCESS | 1964 | |5;imaging-gaussian-blur;130;126;93;89;92;76;129;86;127;125 05:33:50 INFO - PROCESS | 1964 | |6;imaging-darkroom;96;97;96;97;100;96;114;125;96;98 05:33:50 INFO - PROCESS | 1964 | |7;imaging-desaturate;91;94;76;148;143;89;130;107;89;91 05:33:50 INFO - PROCESS | 1964 | |8;json-parse-financial;60;64;62;61;62;62;63;82;64;59 05:33:50 INFO - PROCESS | 1964 | |9;json-stringify-tinderbox;65;68;66;72;76;74;71;67;69;71 05:33:50 INFO - PROCESS | 1964 | |10;stanford-crypto-aes;87;87;86;85;88;86;86;85;86;86 05:33:50 INFO - PROCESS | 1964 | |11;stanford-crypto-ccm;138;154;186;149;150;142;138;139;186;141 05:33:50 INFO - PROCESS | 1964 | |12;stanford-crypto-pbkdf2;167;170;190;167;173;175;173;192;225;178 05:33:50 INFO - PROCESS | 1964 | |13;stanford-crypto-sha256-iterative;66;68;66;69;66;67;65;69;65;69 05:33:50 INFO - PROCESS | 1964 | __end_tp_report 05:33:50 INFO - PROCESS | 1964 | __start_cc_report 05:33:50 INFO - PROCESS | 1964 | _x_x_mozilla_cycle_collect,1084 05:33:50 INFO - PROCESS | 1964 | __end_cc_report 05:33:50 INFO - PROCESS | 1964 | __startTimestamp1462624430830__endTimestamp 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | ------- Summary: start ------- 05:33:50 INFO - PROCESS | 1964 | Number of tests: 14 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#0] ai-astar Cycles:10 Average:125.60 Median:117.50 stddev:21.77 (18.5%) stddev-sans-first:22.53 05:33:50 INFO - PROCESS | 1964 | Values: 112.0 118.0 116.0 169.0 164.0 118.0 109.0 117.0 117.0 116.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:152.50 stddev:27.25 (17.9%) stddev-sans-first:28.83 05:33:50 INFO - PROCESS | 1964 | Values: 139.0 119.0 146.0 159.0 114.0 169.0 161.0 126.0 196.0 113.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#2] audio-dft Cycles:10 Average:205.00 Median:203.50 stddev:11.53 (5.7%) stddev-sans-first:11.65 05:33:50 INFO - PROCESS | 1964 | Values: 195.0 215.0 233.0 204.0 202.0 196.0 206.0 203.0 195.0 201.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#3] audio-fft Cycles:10 Average:82.20 Median:65.00 stddev:34.07 (52.4%) stddev-sans-first:35.74 05:33:50 INFO - PROCESS | 1964 | Values: 68.0 80.0 74.0 158.0 74.0 57.0 59.0 62.0 130.0 60.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#4] audio-oscillator Cycles:10 Average:107.20 Median:80.00 stddev:30.53 (38.2%) stddev-sans-first:30.63 05:33:50 INFO - PROCESS | 1964 | Values: 79.0 139.0 168.0 105.0 81.0 86.0 126.0 82.0 84.0 122.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#5] imaging-gaussian-blur Cycles:10 Average:107.30 Median:81.00 stddev:21.72 (26.8%) stddev-sans-first:21.42 05:33:50 INFO - PROCESS | 1964 | Values: 130.0 126.0 93.0 89.0 92.0 76.0 129.0 86.0 127.0 125.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#6] imaging-darkroom Cycles:10 Average:101.50 Median:96.00 stddev:9.91 (10.3%) stddev-sans-first:10.31 05:33:50 INFO - PROCESS | 1964 | Values: 96.0 97.0 96.0 97.0 100.0 96.0 114.0 125.0 96.0 98.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#7] imaging-desaturate Cycles:10 Average:105.80 Median:89.00 stddev:25.35 (28.5%) stddev-sans-first:26.31 05:33:50 INFO - PROCESS | 1964 | Values: 91.0 94.0 76.0 148.0 143.0 89.0 130.0 107.0 89.0 91.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:62.50 stddev:6.56 (10.5%) stddev-sans-first:6.80 05:33:50 INFO - PROCESS | 1964 | Values: 60.0 64.0 62.0 61.0 62.0 62.0 63.0 82.0 64.0 59.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:71.00 stddev:3.54 (5.0%) stddev-sans-first:3.28 05:33:50 INFO - PROCESS | 1964 | Values: 65.0 68.0 66.0 72.0 76.0 74.0 71.0 67.0 69.0 71.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:86.00 stddev:0.92 (1.1%) stddev-sans-first:0.93 05:33:50 INFO - PROCESS | 1964 | Values: 87.0 87.0 86.0 85.0 88.0 86.0 86.0 85.0 86.0 86.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:149.50 stddev:18.59 (12.4%) stddev-sans-first:18.98 05:33:50 INFO - PROCESS | 1964 | Values: 138.0 154.0 186.0 149.0 150.0 142.0 138.0 139.0 186.0 141.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.00 Median:176.50 stddev:17.71 (10.0%) stddev-sans-first:18.05 05:33:50 INFO - PROCESS | 1964 | Values: 167.0 170.0 190.0 167.0 173.0 175.0 173.0 192.0 225.0 178.0 05:33:50 INFO - PROCESS | 1964 | 05:33:50 INFO - PROCESS | 1964 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.63 (2.4%) stddev-sans-first:1.69 05:33:50 INFO - PROCESS | 1964 | Values: 66.0 68.0 66.0 69.0 66.0 67.0 65.0 69.0 65.0 69.0 05:33:50 INFO - PROCESS | 1964 | -------- Summary: end -------- 05:33:50 INFO - PROCESS | 1964 | 05:33:51 INFO - TEST-INFO | 1964: exit 0 05:33:51 INFO - TEST-OK | kraken | took 60506ms 05:33:51 INFO - SUITE-END | took 1174s 05:33:51 INFO - Completed test suite (00:19:34) 05:33:51 INFO - javascript benchmark 05:33:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3485.6862882340592, 3499.6150423453414, 3530.1527633368, 3534.363814513388, 3557.039599308187, 5846.970765146174, 5906.2912450506, 5968.7314070866805, 5983.461488466037, 6001.729922153508, 5842.912356314648, 5944.138099975501, 5988.64068155911, 6015.6992150392525, 6067.332593414721, 5294.364676238837, 5311.49540793624, 5314.0, 5361.544268737162, 5514.200440936062, 5310.8406747797435, 5459.290292262001, 5463.289772329612, 5533.916991245134, 5542.4457554244655, 5222.164453687392, 5254.579633629301, 5386.676799392049, 5396.676199428047, 5427.213054107152, 3990.7605543667473, 4873.195922672755, 5349.866253343697, 5361.892762144775, 5371.892562148735, 4882.511748825124, 4942.481039490837, 5167.7932882684645, 5280.471952804727, 5297.49673780994, 5689.288838895138, 5727.140928860705, 5808.82573522795, 5991.580589358747, 6024.036154215349, 5305.0, 6232.065190221458, 6273.215848018997, 6300.905486417708, 6334.334894836022, 6317.241930968267, 6324.3991820776955, 6344.809655710336, 6356.2054743157105, 6363.140975968231, 5052.317937078484, 5970.0, 6085.0568161934525, 6134.141220229082, 6137.232845894263, 6425.614463132227, 6492.610443373413, 6494.512911531654, 6504.707288172043, 6521.380468855451, 6179.011358182669, 6216.627002379872, 6231.470325022424, 6246.500279977591, 6246.531510136758, 6103.91351630514, 6256.906146407808, 6266.279377871603, 6351.523635727339, 6390.616563006234, 6155.891939450852, 6180.042093475518, 6208.72060757267, 6222.62664240138, 6227.844303892349, 6115.575014303891, 6248.968755156195, 6276.435120839146, 6281.152044474029, 6317.46301564363, 5037.823676171316, 5047.293378926953, 5049.974750126299, 5074.213496907985, 5100.5409513143995, 3834.6357096075826, 4507.211238033331, 4559.133764584718, 4576.633869290449, 4599.862004139881, 5926.4666180044005, 5979.611325263844, 6055.152278680988, 6078.878422431527, 6112.938870611237, 6215.9378406215355, 6313.747450102036, 6321.336259692758, 6333.46165575922, 6334.5249106317215, 6026.276846778327, 6035.336113027563, 6036.245469316335, 6058.303295121029, 6067.575269731165, 5442.537384322298, 5547.8890422192135, 5558.805441809517, 5569.610127291051, 5593.552515798727, 6607.57050791697, 6611.206655201407, 6613.206415230203, 6636.469082473391, 6640.203175618956, 6346.428821406095, 6360.427561519486, 6365.204349456318, 6374.808755737336, 6386.648734319471, 6336.271328797063, 6408.647524386203, 6427.389398007182, 6465.547411681326, 6506.674666266762, 6238.376162383725, 6246.406591373812, 6246.593971391845, 6255.0, 6260.248770147611, 6342.143810585697, 6360.4911607072245, 6372.74509019634, 6376.266729326187, 6378.553501254868, 6119.106526814713, 6135.508280839814, 6143.450468055886, 6153.169322141633, 6162.506999439945, 6183.96908015457, 6192.721327540181, 6200.224971878515, 6207.534434917489, 6213.565050446426, 6023.879522409439, 6036.746709824061, 6067.848303792441, 6126.172966649446, 6240.4071613196675, 6141.447269745744, 6142.590862071594, 6153.538484613761, 6186.690665466619, 6202.379762023762, 5153.922691159561, 6178.721957511832, 6189.164462797645, 6191.721372538156, 6241.21984751906], "value": 5815.9203624028305, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.71159660002303, 509.48541972607654, 528.5164074871493, 541.553218594659, 559.1780083277582, 35902.0, 40473.59526404735, 40508.79745601272, 40546.59453405465, 40595.39106913395, 41026.7948660257, 41582.58417415825, 41630.58369416305, 41692.79153604235, 41820.372694409605, 9580.0, 9759.072888075621, 9767.658131965383, 9780.462074585896, 9782.21742260621, 9573.282003849705, 9601.711948641518, 9601.759956001086, 9656.468894210815, 9700.902990970106, 9595.280353973445, 9650.276229282797, 9677.56450959705, 9723.319367644268, 9738.464384458852, 9653.565589548487, 9680.806383872354, 9693.0, 9715.319927605035, 9740.074692904163, 9509.429434233969, 9515.286353523514, 9589.328746987643, 9606.90393096067, 9631.422114673142, 37670.81164594187, 38050.0, 38168.23663526714, 38375.0, 38409.42385864214, 3151.7163455289133, 3165.398574270881, 3321.667833216683, 3356.4461863792444, 3425.937959232621, 27369.45261094767, 27975.0, 28581.99963001285, 29125.980590679224, 29170.0, 2481.789047930928, 2547.3886267295716, 2579.0, 2581.728918463572, 2780.624615676878, 528943.0, 529383.353083236, 537554.0, 698501.0, 699463.0, 34970.0, 64006.67996659987, 64301.67849160724, 64561.67719161468, 65141.0, 30559.847200763856, 36343.0, 36781.63218367835, 36878.26243475169, 36878.893633191045, 15495.070295782289, 15497.5350739478, 15525.689486210213, 15532.611684707972, 15590.610234743996, 12722.236665800081, 13079.934600326937, 13114.213147211198, 13148.0, 13151.802722959163, 7877.645505952246, 8197.754067377988, 8219.917800821915, 8234.547099909563, 8262.917370826335, 3241.6596257393107, 3242.1570391697855, 3407.897763067112, 3417.624061353249, 3427.7086447652227, 966.4104896013426, 973.5424350555228, 1061.883192848786, 1076.284270959803, 1104.5084937202867, 28593.0, 29085.0, 29187.124386268446, 29568.852155739514, 29843.552346714816, 992.6228033347281, 1061.543536279392, 1066.7759770448138, 1116.6203490813164, 1186.1400484648734, 15128.0, 15157.469488568067, 15162.0, 15403.922980385027, 15431.228438578251, 682919.0, 687067.0, 688464.0, 688676.5566172238, 692753.0, 59091.52271193254, 59613.0, 59836.401635983086, 59873.0, 60081.399186007584, 1110.4503270880966, 1352.8985326100583, 1371.7599420101642, 1422.0969684250576, 1490.6422458609857, 28860.7113928858, 28892.711072889004, 29022.0, 29140.70859291465, 29314.56028159622, 51199.48800511947, 51482.0, 51639.48360516497, 51657.225141623654, 51780.22329664983, 561052.0, 573500.0, 578774.0, 578863.0, 579622.0, 26660.733392665825, 27067.86466067736, 27093.59359609568, 27113.45773084567, 27173.0, 45172.0, 45278.773606131756, 45554.811163218255, 46261.0, 46411.83970400767, 37211.6278837219, 37395.62604373921, 37520.81239593784, 37827.62172378351, 38093.80953095217, 36143.096422589646, 36847.63152368442, 37015.81492092522, 37301.0, 37522.061948451505, 36036.4594531077, 37154.0, 37258.0, 38369.0, 39991.0, 29287.12138635844, 32566.674333256364, 32873.506897397034, 33429.0, 33994.66005339915, 20469.590608187456, 21971.450713732283, 22533.211337603105, 23223.883880580488, 24750.752492475563], "value": 18624.467542351267, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.17489411166036, 375.4424679351163, 387.53689341237214, 391.794307988306, 403.8041549848321, 11675.241109327888, 18812.717809232854, 20944.581108377824, 21221.68167477487, 21231.0, 20574.765514069168, 21376.0, 22060.558788824215, 22837.543249135048, 23170.88414557925, 5900.868595107168, 6637.469002479802, 6817.556858804187, 6837.965810170942, 6874.725010999579, 6789.0, 6850.280720524348, 6863.588184708909, 6878.484113691468, 7079.398251148658, 7350.301721336491, 7497.400207983348, 7557.622118894061, 7584.582847943361, 7596.810079748023, 7739.729109481169, 7758.650860711282, 7776.883346749803, 7789.649465774026, 7806.33646140076, 7138.357547820721, 7413.481056326059, 7442.962785186093, 7490.0, 7515.699372025112, 28121.875124995182, 28237.822707620107, 28824.27939301513, 29584.70415295841, 29751.661175247165, 2225.877576733279, 2318.9188378406675, 2389.163792672568, 2420.891059902309, 2544.9236522904343, 13460.192388456626, 16151.596210094842, 16370.590735231595, 16521.586960326087, 16946.3221471141, 1828.9725654115198, 1848.6857234270205, 1904.7333373327745, 1936.1868015433552, 1938.4378530226218, 116252.0, 122068.38965805114, 138986.30506847502, 139881.0, 142672.8599071015, 16542.42101526441, 21347.46631334229, 21358.679619805716, 21470.677939830915, 21852.89073554622, 35110.0, 35640.28719425597, 35779.10552236215, 36077.639223607424, 36097.5560977558, 9770.12068913787, 11147.777044459066, 11183.496742646601, 11342.489587968395, 11365.0, 7670.8082297943, 7868.606569671493, 9240.537973101318, 9256.95371523138, 9389.295802814817, 9650.628868037504, 9765.853512197322, 9781.755456113655, 9912.306138570231, 9970.501474926225, 3765.661090501868, 3856.3829787234467, 3891.20230352777, 3981.2775055323364, 4003.2393845169327, 391.7864763703788, 405.5863019719869, 421.0001247038284, 438.7784168994637, 857.9270761985301, 11606.187566870238, 11618.0, 11853.88146118545, 12003.21979071375, 12111.939440302918, 435.87978894241496, 447.25085481817825, 447.6910931457284, 454.9317602359606, 469.5351601914149, 5959.612625179349, 6064.090386442069, 6151.792030768921, 6165.938340616626, 6170.10533472644, 589691.0, 601807.0, 604511.0, 606722.0, 609161.9541902351, 120575.0, 122401.0, 135087.32456337655, 137257.0, 144267.0, 446.73865788513433, 462.0873774295739, 463.16167736396557, 463.6568938985127, 464.2780476359294, 8393.256750708784, 10835.428862815006, 10936.507857146607, 11075.418229267856, 11177.497012634614, 47196.0, 49168.2624760636, 50274.74862625664, 50328.49671503238, 50339.74830125826, 68875.0, 73629.0, 74320.0, 74550.0, 74934.0, 7876.34006482174, 8062.475939063942, 8090.312323452337, 8261.876071859047, 8304.543250121302, 8989.955050224708, 11567.942160289145, 12327.383630818602, 12743.235405875677, 12879.871201288242, 10313.690589282334, 10393.58425662965, 10478.37129772216, 10511.316764410289, 10722.463876805972, 10621.097206737513, 10780.407077610807, 10914.672559823219, 11035.503402347074, 11101.888981110085, 10402.47987600632, 10487.213458990454, 10705.678829635124, 10820.621278255223, 10907.454627268764, 6949.7567585134275, 8175.632096555788, 8226.424150309422, 8291.58542072906, 8416.116307787594, 9215.585298661443, 10126.544305506122, 10470.528826202686, 10554.10290125348, 10726.892731072589], "value": 9808.18239208841, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1564.507180238225, 1589.237166160243, 1848.8428483578896, 1938.0309845077452, 1945.727598136262, 7950.960245198766, 8287.046989596198, 8520.36097292704, 8798.604062817181, 8800.383973121883, 7470.813229669261, 7947.284744373005, 8392.076871544125, 8693.521856297917, 8870.911290887105, 2125.90433430496, 2447.302518782147, 2486.875656217182, 2495.488424872906, 2497.2383423056053, 2351.2123438648073, 2438.1588352018525, 2481.4540801023836, 2491.613799861015, 2496.1887386599337, 2113.4505028692574, 2465.149523414419, 2467.3214865911837, 2489.6638953741194, 2496.3384703053707, 2434.549608322457, 2445.63315502676, 2449.9020039198413, 2451.7180524239675, 2462.0274991378483, 2443.596806526921, 2458.7172475165407, 2458.938526536833, 2466.48203877186, 2471.505698860235, 8760.8685869712, 8853.0, 8868.778280543038, 8889.911100889038, 8933.776655583662, 5371.516563509303, 5483.862903427446, 5545.639533430314, 5548.889022219573, 5550.888982220333, 3483.8432270547887, 3522.3307571561577, 3539.1152211947015, 3576.7138628909624, 3583.372909740785, 881.3478026260585, 1002.4536627538009, 1014.1329163565119, 1015.0255754475712, 1018.8471729240599, 11364.374959377232, 11368.0, 11487.827682584768, 11505.5973040944, 11609.30344179352, 4207.621314081747, 4576.336431217454, 4753.952460475421, 4822.445418776885, 4895.975520122377, 9923.503824808875, 9947.403155810674, 9953.950230248947, 9983.251256155818, 10021.549030293654, 5322.2282768998275, 5334.67991920486, 5359.633293510208, 5429.321334833146, 5462.098753705594, 4371.388005679208, 4738.668293219511, 4942.085714142895, 4955.851324460272, 4959.603231741452, 3639.94540081899, 4000.0599859033105, 4022.7988600569856, 4034.636882680573, 4177.665786737054, 1341.403075631354, 1351.3918736568583, 1354.3025341948903, 1358.65354334646, 1360.3674291454522, 868.8653258744905, 913.7395842184945, 924.9722508324761, 940.4874343482818, 958.0885933788403, 5971.940280597225, 6042.12389203563, 6092.72582733778, 6155.353687862799, 6183.763247350548, 454.9226631472658, 470.54827365729284, 472.0794450820913, 472.3505180376982, 473.1908436573429, 3965.5439624442984, 4024.4969378827645, 4039.5758445363404, 4086.6322031017353, 4102.261592913244, 10296.639617613348, 10825.83761243582, 10852.837207441895, 10854.511546980404, 10886.237963342648, 10657.946710266398, 10717.46412679363, 10773.976472235125, 10827.404492752974, 10830.945845270724, 7891.763247102481, 8128.146544612849, 8244.258016778518, 8270.710525131712, 8282.544460054754, 5160.974195129001, 5847.912281315698, 5896.734646940812, 5965.970170149121, 5991.580589358878, 9744.366616169926, 9761.365511241747, 9832.065953734384, 9855.901440985499, 9910.968251508568, 9779.315447918578, 10159.238057145596, 10248.48757562104, 10275.486225688535, 10370.948145259226, 6157.815265541862, 6949.200841903245, 7500.212477689982, 7538.509996850187, 7594.202608726005, 8404.705835295736, 8428.325733941392, 8501.617427215666, 8502.829943400973, 8533.786655333666, 7041.295870412918, 7053.153621565445, 7339.0, 7507.66215520292, 7590.203028681909, 7094.219635840157, 7351.191368949519, 7419.220981796833, 7488.0, 7513.211112833293, 5894.941050589611, 7035.0150978861975, 7037.894431583428, 7054.29457054311, 7057.258987806206, 4897.363342765418, 4909.643371181782, 5039.067772462106, 5082.771275292695, 5219.869503262449, 4992.8751781205765, 5067.366579177603, 5095.108356037753, 5114.514121158484, 5306.151015837448], "value": 4538.378045017304, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.2196765297012, 312.7529251891006, 318.63516273866423, 322.81760805145075, 324.5650827890626, 4807.44714357849, 4910.75446227689, 5291.518374855047, 5501.349595121463, 5516.172574113885, 4995.4505004449575, 5411.567074634029, 5434.445810469073, 5465.699386533739, 5473.534749546293, 4118.361653943643, 4122.732022418548, 4128.502256135025, 4137.958620413788, 4142.647874930634, 3784.432335149722, 3937.389704595778, 4125.855595054174, 4162.542120366757, 4220.873373798791, 4014.2172276406113, 4039.4546736190678, 4066.715329926906, 4073.103917138225, 4083.8774836754797, 3444.2078321986082, 4061.6344528992236, 4079.6940229482757, 4094.795260237006, 4119.79590918857, 3372.595288565388, 3535.1338921964284, 3536.8054756988354, 3548.8048157351336, 3550.3254381667402, 4161.91676166478, 5107.080725469414, 5181.715005674724, 5336.599755018389, 5345.572354211654, 3938.448617193595, 4664.230401983668, 4696.906061878777, 4710.952890471086, 4731.787069581877, 5216.556592689626, 5733.799317023884, 5767.711614419262, 5771.797987070474, 5779.35288442796, 3327.88352407667, 3795.62043795621, 3913.2390424309174, 3924.3917192835156, 3925.88222353327, 5418.512333889968, 5956.761729530814, 5991.820245392645, 6002.189704389939, 6113.602615829956, 3817.7401457518954, 3843.942340864889, 3917.0990672145567, 3969.821358038895, 3970.265500882346, 3113.875444982221, 3629.673329400314, 3826.445165450994, 3997.5003124609425, 4012.117334186474, 3670.8708948809626, 4528.705634133771, 4609.7695115244105, 4646.744429056367, 4704.153252414599, 5309.707966061904, 5394.78420863169, 5486.506214440639, 5777.931082749705, 5835.824925252249, 1175.9353235572125, 1244.122893360179, 1270.917390369623, 1289.3875409180564, 1309.3976770685592, 713.5540287320425, 852.0669866496177, 861.9439736417238, 889.0664801957919, 891.8483857744199, 4482.744831447135, 4512.939459227079, 4743.169945259566, 5058.620603454756, 5104.259882317117, 4891.706497610084, 5022.146235140035, 5022.723750193702, 5564.4991950724625, 5664.895345407606, 4326.978365108217, 4933.889874778146, 5057.974710126426, 5100.464451232567, 5105.668131571436, 2638.0898589986423, 2991.4145502883507, 2995.6854530274445, 3008.7292143707173, 3036.6963303669454, 5602.0, 5655.773769049192, 5899.4985426239255, 5963.541146135231, 6130.517140558081, 4951.8115652243205, 5220.295260139833, 5576.581756368289, 5583.832485025456, 5839.912401313899, 5205.895882082337, 5290.761915713802, 5873.765049397976, 5981.252343457068, 6014.248218972629, 4758.905451746048, 5478.698671573102, 5490.560755139659, 5746.626469279484, 5801.303843538633, 4691.835785747619, 5028.21498368074, 5051.545360917535, 5221.634485586124, 5283.656562323436, 5375.731213439391, 5494.2582751328055, 5608.775648974159, 5965.791197308074, 5971.4327138921735, 3441.8107004115013, 3597.76580731074, 3663.468797024363, 3693.409054551259, 3819.4843696101784, 4334.133173365402, 5248.580113590827, 5429.511343979061, 5663.96977616973, 5687.3459552152035, 4958.479359667327, 5546.389897111234, 5708.600397972102, 5731.541476681772, 5749.338826035059, 4700.7179569225955, 5231.764570594256, 5319.005372985131, 5681.0058239808695, 5722.284714410699, 5160.432352441153, 5330.786768529216, 5534.280543529316, 5556.305461817273, 5570.442955704397, 4929.605631549396, 5691.77232910679, 5751.8562035949435, 5754.597178197486, 5788.53691704671, 4567.406237189144, 5400.459954004568, 5571.777128914798, 5609.80365687199, 5611.242482264842], "value": 4066.0465706081313, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.3810672700843, 731.8755811512042, 747.2228881962756, 766.1113108793799, 801.2588355770911, 14187.503437379693, 15314.846851531482, 15396.769048464283, 15456.304466299016, 15495.91528592999, 13963.0, 13964.930175349135, 14056.875449964004, 14133.78799318011, 14139.0, 711.3242419701304, 869.5869462005528, 890.8441022821013, 908.1372695938885, 908.318760929303, 866.33292364879, 869.0310304011014, 895.6372669069037, 896.3994123936978, 903.1284810158196, 937.7046230437425, 956.6603855631255, 957.4590356448584, 957.5355952363099, 959.27574681116, 922.9123233292827, 923.0215971070686, 944.2493217891792, 945.508335665457, 955.4458414119794, 691.498663468986, 701.2076353720314, 708.327089265195, 711.1040089487232, 712.5261700968849, 14667.706645867129, 14970.925145374204, 15024.924875375553, 15031.0, 15041.623959400993, 12534.185277956904, 12576.308303043328, 12619.684507887285, 12672.619821405373, 13099.0, 9830.361026533325, 10127.696169114939, 10156.441395723232, 10201.030902064293, 10405.219608529314, 3454.29187016661, 3472.1146107742616, 3494.2662040971427, 3496.30073985204, 3535.7701749386465, 87774.5611271946, 89612.0, 92391.07608923892, 92414.53792731061, 93248.53375733146, 8943.552822358855, 9137.588808503633, 9546.0, 9593.712188634352, 9717.854232186522, 19931.00344982745, 20458.488537786674, 20470.078846451946, 20754.58490830175, 20756.68864967027, 7086.858262834715, 7310.671019804122, 7559.1684914659345, 7785.844283114419, 7822.217778222246, 4027.8993025174605, 4045.838166473367, 4053.959460405358, 4077.734947228421, 4093.038136038029, 3436.759426840097, 3559.1458050068313, 3563.8574457021946, 3583.5878873929832, 3587.6412358763914, 642.0722056628127, 645.4062262718311, 655.0796131435341, 658.4140115297389, 659.2978477920999, 556.5789213064801, 567.3503838105346, 577.3908526504578, 578.320473443702, 599.7061439894508, 7691.153973062958, 7835.6473958671995, 7916.881246781303, 8053.952986111768, 8088.919110808934, 548.0846985534167, 562.353293712226, 577.7515668262687, 588.6468119128489, 593.1725243285679, 3662.505561749183, 3669.046048027532, 3757.248550289953, 3776.811159442072, 3847.711421643388, 58001.0, 75821.24178758252, 88526.11473885308, 88720.5563972176, 90011.54994224987, 59935.0, 77457.225427745, 77492.61253693695, 78473.2152678466, 80767.0, 58748.4125158743, 71876.0, 71975.0, 72888.63555682293, 73337.6333118331, 7483.550986940801, 7519.661615227437, 7567.129780075142, 7634.580098094658, 7684.731034413769, 32744.0, 34561.0, 42290.36564451474, 45545.089098218516, 45577.54422455733, 61189.470263241994, 62113.378866212566, 62523.43691407751, 62961.42596434942, 63056.68471657612, 8563.486190828571, 9882.901170988198, 9894.604215831574, 9916.107550320507, 10405.687829365132, 10689.251752377259, 10797.730056748644, 11041.944790275997, 11086.889131108908, 11234.719132021439, 9557.713268601952, 9574.808503830023, 9805.460699661588, 10039.69880903574, 10401.53193106297, 10049.045340692623, 10064.39613623185, 10351.534180962026, 10392.272540922062, 10455.634052808115, 9526.52367381642, 9576.186024188022, 9656.468894210886, 9887.209023277977, 10247.128994035591, 8466.163553136212, 8680.479171249744, 8683.82632347337, 8757.781055473664, 8905.955470222607, 8071.919280807117, 8393.580320983805, 8660.610272537877, 8804.911950880409, 9054.185123338932], "value": 6636.522354595891, "unit": "score"}], "name": "dromaeo_css", "value": 7118.548196745518, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 118.0, 116.0, 169.0, 164.0, 118.0, 109.0, 117.0, 117.0, 116.0], "value": 125.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 119.0, 146.0, 159.0, 114.0, 169.0, 161.0, 126.0, 196.0, 113.0], "value": 144.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 215.0, 233.0, 204.0, 202.0, 196.0, 206.0, 203.0, 195.0, 201.0], "value": 205.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 80.0, 74.0, 158.0, 74.0, 57.0, 59.0, 62.0, 130.0, 60.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 139.0, 168.0, 105.0, 81.0, 86.0, 126.0, 82.0, 84.0, 122.0], "value": 107.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 126.0, 93.0, 89.0, 92.0, 76.0, 129.0, 86.0, 127.0, 125.0], "value": 107.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 97.0, 100.0, 96.0, 114.0, 125.0, 96.0, 98.0], "value": 101.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 76.0, 148.0, 143.0, 89.0, 130.0, 107.0, 89.0, 91.0], "value": 105.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 62.0, 61.0, 62.0, 62.0, 63.0, 82.0, 64.0, 59.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 66.0, 72.0, 76.0, 74.0, 71.0, 67.0, 69.0, 71.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 85.0, 88.0, 86.0, 86.0, 85.0, 86.0, 86.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 154.0, 186.0, 149.0, 150.0, 142.0, 138.0, 139.0, 186.0, 141.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 190.0, 167.0, 173.0, 175.0, 173.0, 192.0, 225.0, 178.0], "value": 181.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 69.0, 66.0, 67.0, 65.0, 69.0, 65.0, 69.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1599.1000000000001, "alertThreshold": 2.0}]} 05:33:52 INFO - Return code: 0 05:33:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:33:52 ERROR - # TBPL SUCCESS # 05:33:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:33:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:33:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:33:52 INFO - 2016-05-07 05:33:52.166 screenresolution[1969:14663] starting screenresolution argv=screenresolution get 05:33:52 INFO - 2016-05-07 05:33:52.168 screenresolution[1969:14663] Display 0: 1600x1200x32@60 05:33:52 INFO - 2016-05-07 05:33:52.174 screenresolution[1970:14666] starting screenresolution argv=screenresolution list 05:33:52 INFO - Available Modes on Display 0 05:33:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:33:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:33:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:33:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:33:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:33:52 INFO - Intel Iris: 05:33:52 INFO - Chipset Model: Intel Iris 05:33:52 INFO - Type: GPU 05:33:52 INFO - Bus: Built-In 05:33:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:33:52 INFO - Vendor: Intel (0x8086) 05:33:52 INFO - Device ID: 0x0a2e 05:33:52 INFO - Revision ID: 0x0009 05:33:52 INFO - Displays: 05:33:52 INFO - Display: 05:33:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:33:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:33:52 INFO - Display Serial Number: 1600x1200 60 05:33:52 INFO - Main Display: Yes 05:33:52 INFO - Mirror: Off 05:33:52 INFO - Online: Yes 05:33:52 INFO - Rotation: Supported 05:33:52 INFO - Return code: 0 05:33:52 INFO - Running post-action listener: _resource_record_post_action 05:33:52 INFO - ##### 05:33:52 INFO - ##### Finished run-tests step (success) 05:33:52 INFO - ##### 05:33:52 INFO - Running post-run listener: _resource_record_post_run 05:33:53 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 1290240; Write bytes: 702039552; Read time: 13; Write time: 2092 05:33:53 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 379867648; Write bytes: 375326208; Read time: 28953; Write time: 708 05:33:53 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 1290240; Write bytes: 301780992; Read time: 13; Write time: 1331 05:33:53 INFO - Running post-run listener: _upload_blobber_files 05:33:53 INFO - Blob upload gear active. 05:33:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:33:53 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 05:33:53 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:33:53 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:33:53 INFO - (blobuploader) - INFO - Open directory for files ... 05:33:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:33:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:33:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:55 INFO - (blobuploader) - INFO - Done attempting. 05:33:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:33:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:33:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:55 INFO - (blobuploader) - INFO - Done attempting. 05:33:55 INFO - (blobuploader) - INFO - Iteration through files over. 05:33:55 INFO - Return code: 0 05:33:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:33:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:33:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a877f18d13541fa9ad9f53d71e962c8491c0a61468e9f05048371afdb2f42fe81f9bd29082ce9bee27ef2b1bfc3e9a939159d7364863ac15b161e63e0dec503", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3aa8a92a5a0bdbcdc03aa0187f81e74a33ddabd95183792a7602fafb22fc5fc360d4e7a98654f0d5bf932050c1ca1c38f023a70f06a9c29b72c80b9d4676f6a"} 05:33:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:33:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:33:55 INFO - Contents: 05:33:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a877f18d13541fa9ad9f53d71e962c8491c0a61468e9f05048371afdb2f42fe81f9bd29082ce9bee27ef2b1bfc3e9a939159d7364863ac15b161e63e0dec503", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3aa8a92a5a0bdbcdc03aa0187f81e74a33ddabd95183792a7602fafb22fc5fc360d4e7a98654f0d5bf932050c1ca1c38f023a70f06a9c29b72c80b9d4676f6a"} 05:33:55 INFO - Running post-run listener: copy_logs_to_upload_dir 05:33:55 INFO - Copying logs to upload dir... 05:33:55 INFO - mkdir: /builds/slave/test/build/upload/logs 05:33:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.088412 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-05-07 05:33:56.060092) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-07 05:33:56.063965) ========= 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.duKc8jXHAU/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.3E16YzOu01/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a877f18d13541fa9ad9f53d71e962c8491c0a61468e9f05048371afdb2f42fe81f9bd29082ce9bee27ef2b1bfc3e9a939159d7364863ac15b161e63e0dec503", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3aa8a92a5a0bdbcdc03aa0187f81e74a33ddabd95183792a7602fafb22fc5fc360d4e7a98654f0d5bf932050c1ca1c38f023a70f06a9c29b72c80b9d4676f6a"} build_url:https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/KkT7Eo9cQqWxyWB3bG-dyQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a877f18d13541fa9ad9f53d71e962c8491c0a61468e9f05048371afdb2f42fe81f9bd29082ce9bee27ef2b1bfc3e9a939159d7364863ac15b161e63e0dec503", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3aa8a92a5a0bdbcdc03aa0187f81e74a33ddabd95183792a7602fafb22fc5fc360d4e7a98654f0d5bf932050c1ca1c38f023a70f06a9c29b72c80b9d4676f6a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-07 05:33:56.097194) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 05:33:56.097909) ========= 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.duKc8jXHAU/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.3E16YzOu01/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005404 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-07 05:33:56.146323) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-07 05:33:56.146638) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-07 05:33:56.146940) ========= ========= Total master_lag: 0.17 =========