builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0097 starttime: 1461759034.01 results: success (0) buildid: 20160427030215 builduid: 966ba7d5b9da44949d1a6e4313ca1bd1 revision: ab0044bfa1df858919797bcd6a9aef76a668cd4a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:34.011012) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:34.011461) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:34.011738) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:34.030464) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:34.030755) ========= 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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-27 05:10:34-- 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% 50.8M=0s 2016-04-27 05:10:34 (50.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112550 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:34.163285) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:34.163665) ========= 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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.020138 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:34.221540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 05:10:34.226312) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ab0044bfa1df858919797bcd6a9aef76a668cd4a --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 ab0044bfa1df858919797bcd6a9aef76a668cd4a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-27 05:10:34,300 truncating revision to first 12 chars 2016-04-27 05:10:34,300 Setting DEBUG logging. 2016-04-27 05:10:34,300 attempt 1/10 2016-04-27 05:10:34,300 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ab0044bfa1df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 05:10:35,529 unpacking tar archive at: mozilla-central-ab0044bfa1df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.473774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 05:10:35.719522) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:35.719836) ========= script_repo_revision: ab0044bfa1df858919797bcd6a9aef76a668cd4a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:35.720233) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:35.720502) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 05:10:35.735630) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-27 05:10:35.735926) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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:10:35 INFO - MultiFileLogger online at 20160427 05:10:35 in /builds/slave/test 05:10:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:10:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:10:35 INFO - {'append_to_log': False, 05:10:35 INFO - 'base_work_dir': '/builds/slave/test', 05:10:35 INFO - 'blob_upload_branch': 'Firefox', 05:10:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:10:35 INFO - 'branch': 'Firefox', 05:10:35 INFO - 'buildbot_json_path': 'buildprops.json', 05:10:35 INFO - 'config_files': ('talos/mac_config.py',), 05:10:35 INFO - 'default_actions': ('clobber', 05:10:35 INFO - 'read-buildbot-config', 05:10:35 INFO - 'download-and-extract', 05:10:35 INFO - 'populate-webroot', 05:10:35 INFO - 'create-virtualenv', 05:10:35 INFO - 'install', 05:10:35 INFO - 'run-tests'), 05:10:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:10:35 INFO - 'download_minidump_stackwalk': True, 05:10:35 INFO - 'download_symbols': 'ondemand', 05:10:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:10:35 INFO - 'tooltool.py': '/tools/tooltool.py', 05:10:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:10:35 INFO - '/tools/misc-python/virtualenv.py')}, 05:10:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:10:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:10:35 INFO - 'installer_path': 'installer.exe', 05:10:35 INFO - 'log_level': 'info', 05:10:35 INFO - 'log_name': 'talos', 05:10:35 INFO - 'log_to_console': True, 05:10:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:10:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:10:35 INFO - 'opt_config_files': (), 05:10:35 INFO - 'pip_index': False, 05:10:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:35 INFO - 'cmd': ('bash', 05:10:35 INFO - '-c', 05:10:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:35 INFO - 'enabled': True, 05:10:35 INFO - 'halt_on_failure': False, 05:10:35 INFO - 'name': 'check_screen_resolution'},), 05:10:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:35 INFO - 'cmd': ('bash', 05:10:35 INFO - '-c', 05:10:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:35 INFO - 'enabled': True, 05:10:35 INFO - 'halt_on_failure': False, 05:10:35 INFO - 'name': 'check_screen_resolution'},), 05:10:35 INFO - 'run_cmd_checks_enabled': True, 05:10:35 INFO - 'sps_profile': False, 05:10:35 INFO - 'sps_profile_interval': 0, 05:10:35 INFO - 'suite': 'dromaeojs', 05:10:35 INFO - 'system_bits': '32', 05:10:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:10:35 INFO - 'title': 't-yosemite-r7-0097', 05:10:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:10:35 INFO - 'use_talos_json': True, 05:10:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:10:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:10:35 INFO - 'work_dir': 'build'} 05:10:35 INFO - ##### 05:10:35 INFO - ##### Running clobber step. 05:10:35 INFO - ##### 05:10:35 INFO - Running pre-action listener: _resource_record_pre_action 05:10:35 INFO - Running main action method: clobber 05:10:35 INFO - rmtree: /builds/slave/test/build 05:10:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:10:37 INFO - Running post-action listener: _resource_record_post_action 05:10:37 INFO - ##### 05:10:37 INFO - ##### Running read-buildbot-config step. 05:10:37 INFO - ##### 05:10:37 INFO - Running pre-action listener: _resource_record_pre_action 05:10:37 INFO - Running main action method: read_buildbot_config 05:10:37 INFO - Using buildbot properties: 05:10:37 INFO - { 05:10:37 INFO - "project": "", 05:10:37 INFO - "product": "firefox", 05:10:37 INFO - "script_repo_revision": "production", 05:10:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:10:37 INFO - "repository": "", 05:10:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:10:37 INFO - "buildid": "20160427030215", 05:10:37 INFO - "stage_platform": "macosx64", 05:10:37 INFO - "basedir": "/builds/slave/test", 05:10:37 INFO - "nightly_build": "True", 05:10:37 INFO - "buildnumber": 241, 05:10:37 INFO - "slavename": "t-yosemite-r7-0097", 05:10:37 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:10:37 INFO - "platform": "yosemite_r7", 05:10:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:10:37 INFO - "branch": "mozilla-central", 05:10:37 INFO - "repo_path": "mozilla-central", 05:10:37 INFO - "revision": "ab0044bfa1df858919797bcd6a9aef76a668cd4a", 05:10:37 INFO - "pgo_build": "False", 05:10:37 INFO - "builduid": "966ba7d5b9da44949d1a6e4313ca1bd1", 05:10:37 INFO - "slavebuilddir": "test" 05:10:37 INFO - } 05:10:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:10:37 INFO - Running post-action listener: _resource_record_post_action 05:10:37 INFO - ##### 05:10:37 INFO - ##### Running download-and-extract step. 05:10:37 INFO - ##### 05:10:37 INFO - Running pre-action listener: _resource_record_pre_action 05:10:37 INFO - Running main action method: download_and_extract 05:10:37 INFO - mkdir: /builds/slave/test/build/tests 05:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:37 INFO - https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:10:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/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:10:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/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:10:42 INFO - Downloaded 1199 bytes. 05:10:42 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:42 INFO - Using the following test package requirements: 05:10:42 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:10:42 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:42 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:10:42 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:42 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:10:42 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:42 INFO - u'jsshell-mac.zip'], 05:10:42 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:42 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:10:42 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:10:42 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:42 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:10:42 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:42 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:10:42 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:42 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:10:42 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:42 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:10:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:42 INFO - https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:10:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:10:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:10:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/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:10:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/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:10:43 INFO - Downloaded 18117418 bytes. 05:10: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:10: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:10:46 INFO - Return code: 0 05:10: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:10: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:10:46 INFO - https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:10:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:10:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:10:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/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:10:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/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:10:48 INFO - Downloaded 18117418 bytes. 05:10:48 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:10:48 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:10:52 INFO - Return code: 0 05:10:52 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:10:52 INFO - https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:10:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:10:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:10:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/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:10:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/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:10:53 INFO - Downloaded 10840145 bytes. 05:10:53 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:10:53 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:10:53 INFO - Return code: 0 05:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:53 INFO - https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:10:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:10:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:10:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:10:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GktKpQirTjuQ-5fVSbTY8w/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:10:56 INFO - Downloaded 110338122 bytes. 05:10:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:10:56 INFO - mkdir: /builds/slave/test/properties 05:10:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:10:56 INFO - Writing to file /builds/slave/test/properties/build_url 05:10:56 INFO - Contents: 05:10:56 INFO - build_url:https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:10:57 INFO - Running post-action listener: _resource_record_post_action 05:10:57 INFO - Running post-action listener: set_extra_try_arguments 05:10:57 INFO - ##### 05:10:57 INFO - ##### Running populate-webroot step. 05:10:57 INFO - ##### 05:10:57 INFO - Running pre-action listener: _resource_record_pre_action 05:10:57 INFO - Running main action method: populate_webroot 05:10:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:10:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:10:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:10:57 INFO - '--tppagecycles', 05:10:57 INFO - '7'], 05:10:57 INFO - 'tests': ['tsvgm']}}, 05:10:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:10:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:10:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:10:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:10:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:10:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:10:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:57 INFO - 'talos_options': ['--disable-e10s'], 05:10:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:10:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:10:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:57 INFO - 'talos_options': ['--disable-e10s'], 05:10:57 INFO - 'tests': ['damp', 'tps']}, 05:10:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:57 INFO - 'tests': ['damp', 'tps']}, 05:10:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:10:57 INFO - 'tests': ['dromaeo_dom']}, 05:10:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:10:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:10:57 INFO - 'tests': ['a11yr', 05:10:57 INFO - 'ts_paint', 05:10:57 INFO - 'tpaint', 05:10:57 INFO - 'sessionrestore', 05:10:57 INFO - 'sessionrestore_no_auto_restore', 05:10:57 INFO - 'tabpaint']}, 05:10:57 INFO - 'other-e10s': {'tests': ['a11yr', 05:10:57 INFO - 'ts_paint', 05:10:57 INFO - 'tpaint', 05:10:57 INFO - 'sessionrestore', 05:10:57 INFO - 'sessionrestore_no_auto_restore', 05:10:57 INFO - 'tabpaint']}, 05:10:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:10:57 INFO - 'ts_paint', 05:10:57 INFO - 'tpaint', 05:10:57 INFO - 'sessionrestore', 05:10:57 INFO - 'sessionrestore_no_auto_restore', 05:10:57 INFO - 'tabpaint']}, 05:10:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:10:57 INFO - 'ts_paint', 05:10:57 INFO - 'tpaint', 05:10:57 INFO - 'sessionrestore', 05:10:57 INFO - 'sessionrestore_no_auto_restore', 05:10:57 INFO - 'tabpaint']}, 05:10:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:10:57 INFO - 'tests': ['a11yr', 05:10:57 INFO - 'ts_paint', 05:10:57 INFO - 'tpaint', 05:10:57 INFO - 'sessionrestore', 05:10:57 INFO - 'sessionrestore_no_auto_restore', 05:10:57 INFO - 'tabpaint']}, 05:10:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:10:57 INFO - 'tests': ['a11yr', 05:10:57 INFO - 'ts_paint', 05:10:57 INFO - 'tpaint', 05:10:57 INFO - 'sessionrestore', 05:10:57 INFO - 'sessionrestore_no_auto_restore', 05:10:57 INFO - 'tabpaint']}, 05:10:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:10:57 INFO - 'tests': ['tsvgx', 05:10:57 INFO - 'tsvgr_opacity', 05:10:57 INFO - 'tart', 05:10:57 INFO - 'tscrollx', 05:10:57 INFO - 'cart']}, 05:10:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:10:57 INFO - 'tsvgr_opacity', 05:10:57 INFO - 'tart', 05:10:57 INFO - 'tscrollx', 05:10:57 INFO - 'cart']}, 05:10:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:57 INFO - 'talos_options': ['--disable-e10s'], 05:10:57 INFO - 'tests': ['tp5o']}, 05:10:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:57 INFO - 'tests': ['tp5o']}, 05:10:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:57 INFO - 'talos_options': ['--disable-e10s', 05:10:57 INFO - '--xperf_path', 05:10:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:10:57 INFO - 'tests': ['tp5n']}, 05:10:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:57 INFO - 'talos_options': ['--xperf_path', 05:10:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:10:57 INFO - 'tests': ['tp5n']}}, 05:10:57 INFO - 'talos.zip': {'path': '', 05:10:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:10:57 INFO - Running post-action listener: _resource_record_post_action 05:10:57 INFO - ##### 05:10:57 INFO - ##### Running create-virtualenv step. 05:10:57 INFO - ##### 05:10:57 INFO - Running pre-action listener: _resource_record_pre_action 05:10:57 INFO - Running main action method: create_virtualenv 05:10:57 INFO - Creating virtualenv /builds/slave/test/build/venv 05:10:57 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:10:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:10:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:10:58 INFO - Using real prefix '/tools/python27' 05:10:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:10:58 INFO - Installing distribute.............................................................................................................................................................................................done. 05:11:01 INFO - Installing pip.................done. 05:11:01 INFO - Return code: 0 05:11:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac795d2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104892e00>, '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 0x1046bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488db20>, '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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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:11:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:11:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:11:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YSJdxw3Q2i/Render', 05:11:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:01 INFO - 'HOME': '/Users/cltbld', 05:11:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:01 INFO - 'LOGNAME': 'cltbld', 05:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:01 INFO - 'MOZ_NO_REMOTE': '1', 05:11:01 INFO - 'NO_EM_RESTART': '1', 05:11:01 INFO - 'PAGER': '/bin/cat', 05:11:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:01 INFO - 'PWD': '/builds/slave/test', 05:11:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:01 INFO - 'SHELL': '/bin/bash', 05:11:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aL2HsM1FIi/Listeners', 05:11:01 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:11:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:01 INFO - 'USER': 'cltbld', 05:11:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:01 INFO - 'XPC_FLAGS': '0x0', 05:11:01 INFO - 'XPC_SERVICE_NAME': '0', 05:11:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:01 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:11:01 INFO - Cleaning up... 05:11:01 INFO - Return code: 0 05:11:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1049f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac795d2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104892e00>, '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 0x1046bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488db20>, '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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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:11:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:11:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:11:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YSJdxw3Q2i/Render', 05:11:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:01 INFO - 'HOME': '/Users/cltbld', 05:11:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:01 INFO - 'LOGNAME': 'cltbld', 05:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:01 INFO - 'MOZ_NO_REMOTE': '1', 05:11:01 INFO - 'NO_EM_RESTART': '1', 05:11:01 INFO - 'PAGER': '/bin/cat', 05:11:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:01 INFO - 'PWD': '/builds/slave/test', 05:11:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:01 INFO - 'SHELL': '/bin/bash', 05:11:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aL2HsM1FIi/Listeners', 05:11:01 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:11:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:01 INFO - 'USER': 'cltbld', 05:11:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:01 INFO - 'XPC_FLAGS': '0x0', 05:11:01 INFO - 'XPC_SERVICE_NAME': '0', 05:11:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:02 INFO - Downloading/unpacking psutil>=0.7.1 05:11:02 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:11:02 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:11:02 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:11:02 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:11:02 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:11:02 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:11:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:11:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:05 INFO - Installing collected packages: psutil 05:11:05 INFO - Running setup.py install for psutil 05:11:05 INFO - building 'psutil._psutil_osx' extension 05:11:05 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:11:05 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:11:05 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:11:05 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:11:05 INFO - building 'psutil._psutil_posix' extension 05:11:05 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:11:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:11:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:11:05 INFO - ^ 05:11:05 INFO - 1 warning generated. 05:11:05 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:11:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:05 INFO - Successfully installed psutil 05:11:05 INFO - Cleaning up... 05:11:05 INFO - Return code: 0 05:11:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:11:05 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:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:05 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:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:05 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 0x1049f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac795d2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104892e00>, '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 0x1046bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488db20>, '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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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:11:05 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:11:05 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:11:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YSJdxw3Q2i/Render', 05:11:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:05 INFO - 'HOME': '/Users/cltbld', 05:11:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:05 INFO - 'LOGNAME': 'cltbld', 05:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:05 INFO - 'MOZ_NO_REMOTE': '1', 05:11:05 INFO - 'NO_EM_RESTART': '1', 05:11:05 INFO - 'PAGER': '/bin/cat', 05:11:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:05 INFO - 'PWD': '/builds/slave/test', 05:11:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:05 INFO - 'SHELL': '/bin/bash', 05:11:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aL2HsM1FIi/Listeners', 05:11:05 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:11:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:05 INFO - 'USER': 'cltbld', 05:11:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:05 INFO - 'XPC_FLAGS': '0x0', 05:11:05 INFO - 'XPC_SERVICE_NAME': '0', 05:11:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:11:08 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:11:08 INFO - Installing collected packages: mozsystemmonitor 05:11:08 INFO - Running setup.py install for mozsystemmonitor 05:11:08 INFO - Successfully installed mozsystemmonitor 05:11:08 INFO - Cleaning up... 05:11:08 INFO - Return code: 0 05:11:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:11:08 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:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:08 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:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:08 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 0x1049f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac795d2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104892e00>, '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 0x1046bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488db20>, '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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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:11:08 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:11:08 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:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YSJdxw3Q2i/Render', 05:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:08 INFO - 'HOME': '/Users/cltbld', 05:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:08 INFO - 'LOGNAME': 'cltbld', 05:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:08 INFO - 'MOZ_NO_REMOTE': '1', 05:11:08 INFO - 'NO_EM_RESTART': '1', 05:11:08 INFO - 'PAGER': '/bin/cat', 05:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:08 INFO - 'PWD': '/builds/slave/test', 05:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:08 INFO - 'SHELL': '/bin/bash', 05:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aL2HsM1FIi/Listeners', 05:11:08 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:08 INFO - 'USER': 'cltbld', 05:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:08 INFO - 'XPC_FLAGS': '0x0', 05:11:08 INFO - 'XPC_SERVICE_NAME': '0', 05:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:09 INFO - Downloading/unpacking blobuploader==1.2.4 05:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:11 INFO - Downloading blobuploader-1.2.4.tar.gz 05:11:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:11:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:11:11 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:11:11 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:11:11 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:11:11 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:11:11 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:11:11 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:11:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:11:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:12 INFO - Downloading docopt-0.6.1.tar.gz 05:11:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:11:12 INFO - Installing collected packages: blobuploader, requests, docopt 05:11:12 INFO - Running setup.py install for blobuploader 05:11:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:11:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:11:12 INFO - Running setup.py install for requests 05:11:13 INFO - Running setup.py install for docopt 05:11:13 INFO - Successfully installed blobuploader requests docopt 05:11:13 INFO - Cleaning up... 05:11:13 INFO - Return code: 0 05:11:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:13 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 0x1049f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac795d2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104892e00>, '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 0x1046bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488db20>, '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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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:11:13 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:11:13 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:11:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YSJdxw3Q2i/Render', 05:11:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:13 INFO - 'HOME': '/Users/cltbld', 05:11:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:13 INFO - 'LOGNAME': 'cltbld', 05:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:13 INFO - 'MOZ_NO_REMOTE': '1', 05:11:13 INFO - 'NO_EM_RESTART': '1', 05:11:13 INFO - 'PAGER': '/bin/cat', 05:11:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:13 INFO - 'PWD': '/builds/slave/test', 05:11:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:13 INFO - 'SHELL': '/bin/bash', 05:11:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aL2HsM1FIi/Listeners', 05:11:13 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:11:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:13 INFO - 'USER': 'cltbld', 05:11:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:13 INFO - 'XPC_FLAGS': '0x0', 05:11:13 INFO - 'XPC_SERVICE_NAME': '0', 05:11:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:11:13 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-SSLJee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:11:14 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-S4QD8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:11:14 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-O6W_R8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:11:14 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ogh06P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:11:14 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-vJj5w2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:11:14 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-4xMS1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:11:14 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-USlWNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:11:14 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-hqdHfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:11:14 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-20Uj8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:11:14 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-LFoUss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:11:14 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Asnr2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:11:15 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-imHhcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:11:15 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-YPcTDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:11:15 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-_hUZoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:15 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-7rGr1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:11:15 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-dKZw5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:15 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Z6rocC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:11:15 INFO - Running setup.py install for manifestparser 05:11:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:11:15 INFO - Running setup.py install for mozcrash 05:11:16 INFO - Running setup.py install for mozdebug 05:11:16 INFO - Running setup.py install for mozdevice 05:11:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:11:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:11:16 INFO - Running setup.py install for mozfile 05:11:16 INFO - Running setup.py install for mozhttpd 05:11:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:11:16 INFO - Running setup.py install for mozinfo 05:11:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:11:16 INFO - Running setup.py install for mozInstall 05:11:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:11:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:11:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:11:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:11:16 INFO - Running setup.py install for mozleak 05:11:17 INFO - Running setup.py install for mozlog 05:11:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:11:17 INFO - Running setup.py install for moznetwork 05:11:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:11:17 INFO - Running setup.py install for mozprocess 05:11:17 INFO - Running setup.py install for mozprofile 05:11:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:11:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:11:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:11:17 INFO - Running setup.py install for mozrunner 05:11:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:11:17 INFO - Running setup.py install for mozscreenshot 05:11:18 INFO - Running setup.py install for moztest 05:11:18 INFO - Running setup.py install for mozversion 05:11:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:11:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:11:18 INFO - Cleaning up... 05:11:18 INFO - Return code: 0 05:11:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:18 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 0x1049f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac795d2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104892e00>, '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 0x1046bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488db20>, '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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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:11:18 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:11:18 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:11:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YSJdxw3Q2i/Render', 05:11:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:18 INFO - 'HOME': '/Users/cltbld', 05:11:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:18 INFO - 'LOGNAME': 'cltbld', 05:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:18 INFO - 'MOZ_NO_REMOTE': '1', 05:11:18 INFO - 'NO_EM_RESTART': '1', 05:11:18 INFO - 'PAGER': '/bin/cat', 05:11:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:18 INFO - 'PWD': '/builds/slave/test', 05:11:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:18 INFO - 'SHELL': '/bin/bash', 05:11:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aL2HsM1FIi/Listeners', 05:11:18 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:11:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:18 INFO - 'USER': 'cltbld', 05:11:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:18 INFO - 'XPC_FLAGS': '0x0', 05:11:18 INFO - 'XPC_SERVICE_NAME': '0', 05:11:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:11:18 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ejraxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:11:18 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:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:11:18 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-sEyOJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:11:18 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:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:11:18 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-VKU9h7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:11:18 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:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:11:18 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-_LS8dL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:11:19 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:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:11:19 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-3UOfCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:11:19 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:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:11:19 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ioRhJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:11:19 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:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:11:19 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-MBcqaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:11:19 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:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:11:19 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-4vWqOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:11:19 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:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:11:19 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-oI4Dne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:11:19 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:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:11:19 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-oEmVry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:11:19 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:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:11:19 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-582OoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:11:19 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:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:11:19 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ZKzGNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:11:19 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:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:11:19 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ghx8b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:11:19 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:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:11:19 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-057vK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:11:20 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:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:20 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-mKFlxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:20 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:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:11:20 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-KjxhUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11:20 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:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:20 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-hyCTQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11:20 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:11:20 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:11:20 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:11:20 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:11:20 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:11:20 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:11:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:11: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:11: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:11: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:11: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:11: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:11: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:11:22 INFO - Downloading blessings-1.6.tar.gz 05:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:11:22 INFO - Installing collected packages: blessings 05:11:22 INFO - Running setup.py install for blessings 05:11:23 INFO - Successfully installed blessings 05:11:23 INFO - Cleaning up... 05:11:23 INFO - Return code: 0 05:11:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:11:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:23 INFO - Reading from file tmpfile_stdout 05:11:23 INFO - Current package versions: 05:11:23 INFO - blessings == 1.6 05:11:23 INFO - blobuploader == 1.2.4 05:11:23 INFO - docopt == 0.6.1 05:11:23 INFO - manifestparser == 1.1 05:11:23 INFO - mozInstall == 1.12 05:11:23 INFO - mozcrash == 0.17 05:11:23 INFO - mozdebug == 0.1 05:11:23 INFO - mozdevice == 0.48 05:11:23 INFO - mozfile == 1.2 05:11:23 INFO - mozhttpd == 0.7 05:11:23 INFO - mozinfo == 0.9 05:11:23 INFO - mozleak == 0.1 05:11:23 INFO - mozlog == 3.1 05:11:23 INFO - moznetwork == 0.27 05:11:23 INFO - mozprocess == 0.22 05:11:23 INFO - mozprofile == 0.28 05:11:23 INFO - mozrunner == 6.11 05:11:23 INFO - mozscreenshot == 0.1 05:11:23 INFO - mozsystemmonitor == 0.0 05:11:23 INFO - moztest == 0.7 05:11:23 INFO - mozversion == 1.4 05:11:23 INFO - psutil == 3.1.1 05:11:23 INFO - requests == 1.2.3 05:11:23 INFO - wsgiref == 0.1.2 05:11:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:23 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 0x1049f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac795d2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104892e00>, '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 0x1046bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488db20>, '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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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:11:23 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:11:23 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:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YSJdxw3Q2i/Render', 05:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:23 INFO - 'HOME': '/Users/cltbld', 05:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:23 INFO - 'LOGNAME': 'cltbld', 05:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:23 INFO - 'MOZ_NO_REMOTE': '1', 05:11:23 INFO - 'NO_EM_RESTART': '1', 05:11:23 INFO - 'PAGER': '/bin/cat', 05:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:23 INFO - 'PWD': '/builds/slave/test', 05:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:23 INFO - 'SHELL': '/bin/bash', 05:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aL2HsM1FIi/Listeners', 05:11:23 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:23 INFO - 'USER': 'cltbld', 05:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:23 INFO - 'XPC_FLAGS': '0x0', 05:11:23 INFO - 'XPC_SERVICE_NAME': '0', 05:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:23 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:11:23 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:11:23 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:11:23 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:11:23 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:11:23 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:11:23 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:11:23 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:11:23 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:11:23 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:11:23 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:11:23 INFO - Cleaning up... 05:11:23 INFO - Return code: 0 05:11:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:23 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 0x1049f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac795d2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104892e00>, '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 0x1046bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488db20>, '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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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:11:23 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:11:23 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:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YSJdxw3Q2i/Render', 05:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:23 INFO - 'HOME': '/Users/cltbld', 05:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:23 INFO - 'LOGNAME': 'cltbld', 05:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:23 INFO - 'MOZ_NO_REMOTE': '1', 05:11:23 INFO - 'NO_EM_RESTART': '1', 05:11:23 INFO - 'PAGER': '/bin/cat', 05:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:23 INFO - 'PWD': '/builds/slave/test', 05:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:23 INFO - 'SHELL': '/bin/bash', 05:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aL2HsM1FIi/Listeners', 05:11:23 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:23 INFO - 'USER': 'cltbld', 05:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:23 INFO - 'XPC_FLAGS': '0x0', 05:11:23 INFO - 'XPC_SERVICE_NAME': '0', 05:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:23 INFO - Downloading/unpacking jsonschema 05:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:11:26 INFO - Downloading/unpacking functools32 (from jsonschema) 05:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:26 INFO - Downloading functools32-3.2.3-2.tar.gz 05:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:11:26 INFO - warning: no files found matching '*.txt' 05:11:26 INFO - no previously-included directories found matching 'build' 05:11:26 INFO - no previously-included directories found matching 'dist' 05:11:26 INFO - no previously-included directories found matching '.git*' 05:11:26 INFO - Installing collected packages: jsonschema, functools32 05:11:26 INFO - Running setup.py install for functools32 05:11:26 INFO - warning: no files found matching '*.txt' 05:11:26 INFO - no previously-included directories found matching 'build' 05:11:26 INFO - no previously-included directories found matching 'dist' 05:11:26 INFO - no previously-included directories found matching '.git*' 05:11:27 INFO - Successfully installed jsonschema functools32 05:11:27 INFO - Cleaning up... 05:11:27 INFO - Return code: 0 05:11:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:11: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:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:27 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 0x1049f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac795d2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104892e00>, '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 0x1046bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488db20>, '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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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:11:27 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:11:27 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:11:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YSJdxw3Q2i/Render', 05:11:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:27 INFO - 'HOME': '/Users/cltbld', 05:11:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:27 INFO - 'LOGNAME': 'cltbld', 05:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:27 INFO - 'MOZ_NO_REMOTE': '1', 05:11:27 INFO - 'NO_EM_RESTART': '1', 05:11:27 INFO - 'PAGER': '/bin/cat', 05:11:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:27 INFO - 'PWD': '/builds/slave/test', 05:11:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:27 INFO - 'SHELL': '/bin/bash', 05:11:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aL2HsM1FIi/Listeners', 05:11:27 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:11:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:27 INFO - 'USER': 'cltbld', 05:11:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:27 INFO - 'XPC_FLAGS': '0x0', 05:11:27 INFO - 'XPC_SERVICE_NAME': '0', 05:11:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:27 INFO - Downloading/unpacking flake8 05:11:27 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:11:27 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:11:27 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:11:27 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:11:27 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:11:27 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:11:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:11:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:11: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:11: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:11: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:11: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:11: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:11: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:11:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:11:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 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:11:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:11:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:11:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:11:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:11:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:11:31 INFO - no previously-included directories found matching 'docs/_build' 05:11:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:11:31 INFO - Running setup.py install for pep8 05:11:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:11:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:11:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:11:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:11:31 INFO - no previously-included directories found matching 'docs/_build' 05:11:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:11:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:11:31 INFO - Cleaning up... 05:11:31 INFO - Return code: 0 05:11:31 INFO - Running post-action listener: _resource_record_post_action 05:11:31 INFO - Running post-action listener: _start_resource_monitoring 05:11:31 INFO - Starting resource monitoring. 05:11:31 INFO - ##### 05:11:31 INFO - ##### Running install step. 05:11:31 INFO - ##### 05:11:31 INFO - Running pre-action listener: _resource_record_pre_action 05:11:31 INFO - Running main action method: install 05:11:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:31 INFO - Reading from file tmpfile_stdout 05:11:31 INFO - Detecting whether we're running mozinstall >=1.0... 05:11:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:11:31 INFO - Reading from file tmpfile_stdout 05:11:31 INFO - Output received: 05:11:31 INFO - Usage: mozinstall [options] installer 05:11:31 INFO - Options: 05:11:31 INFO - -h, --help show this help message and exit 05:11:31 INFO - -d DEST, --destination=DEST 05:11:31 INFO - Directory to install application into. [default: 05:11:31 INFO - "/builds/slave/test"] 05:11:31 INFO - --app=APP Application being installed. [default: firefox] 05:11:31 INFO - mkdir: /builds/slave/test/build/application 05:11:31 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:11:31 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:12:05 INFO - Reading from file tmpfile_stdout 05:12:05 INFO - Output received: 05:12:05 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:12:05 INFO - Running post-action listener: _resource_record_post_action 05:12:05 INFO - ##### 05:12:05 INFO - ##### Running run-tests step. 05:12:05 INFO - ##### 05:12:05 INFO - Running pre-action listener: _resource_record_pre_action 05:12:05 INFO - Running main action method: run_tests 05:12:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:12:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:12:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:12:05 INFO - 2016-04-27 05:12:05.145 screenresolution[1912:14213] starting screenresolution argv=screenresolution get 05:12:05 INFO - 2016-04-27 05:12:05.147 screenresolution[1912:14213] Display 0: 1600x1200x32@60 05:12:05 INFO - 2016-04-27 05:12:05.153 screenresolution[1913:14215] starting screenresolution argv=screenresolution list 05:12:05 INFO - Available Modes on Display 0 05:12:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:12:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:12:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:12:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:12:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:12:05 INFO - Intel Iris: 05:12:05 INFO - Chipset Model: Intel Iris 05:12:05 INFO - Type: GPU 05:12:05 INFO - Bus: Built-In 05:12:05 INFO - VRAM (Dynamic, Max): 1536 MB 05:12:05 INFO - Vendor: Intel (0x8086) 05:12:05 INFO - Device ID: 0x0a2e 05:12:05 INFO - Revision ID: 0x0009 05:12:05 INFO - Displays: 05:12:05 INFO - Display: 05:12:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:12:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:12:05 INFO - Display Serial Number: 1600x1200 60 05:12:05 INFO - Main Display: Yes 05:12:05 INFO - Mirror: Off 05:12:05 INFO - Online: Yes 05:12:05 INFO - Rotation: Supported 05:12:05 INFO - Return code: 0 05:12:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:12:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:12:05 INFO - Python 2.7.3 05:12:05 INFO - Return code: 0 05:12:05 INFO - grabbing minidump binary from tooltool 05:12:05 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:05 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 0x104892e00>, '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 0x1046bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10488db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:12:05 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:12:05 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:12:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:12:05 INFO - Return code: 0 05:12:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:12:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:12:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:12:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:12:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:12:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:12:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:12:06 INFO - Return code: 0 05:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0097 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:12:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YSJdxw3Q2i/Render', 05:12:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:06 INFO - 'HOME': '/Users/cltbld', 05:12:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:06 INFO - 'LOGNAME': 'cltbld', 05:12:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:12:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:06 INFO - 'MOZ_NO_REMOTE': '1', 05:12:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:12:06 INFO - 'NO_EM_RESTART': '1', 05:12:06 INFO - 'PAGER': '/bin/cat', 05:12:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:06 INFO - 'PWD': '/builds/slave/test', 05:12:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:12:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:06 INFO - 'SHELL': '/bin/bash', 05:12:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aL2HsM1FIi/Listeners', 05:12:06 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:12:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:06 INFO - 'USER': 'cltbld', 05:12:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:06 INFO - 'XPC_FLAGS': '0x0', 05:12:06 INFO - 'XPC_SERVICE_NAME': '0', 05:12:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:12:06 INFO - mozversion application_buildid: 20160427030215 05:12:06 INFO - mozversion application_changeset: ab0044bfa1df858919797bcd6a9aef76a668cd4a 05:12:06 INFO - mozversion application_display_name: Nightly 05:12:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:12:06 INFO - mozversion application_name: Firefox 05:12:06 INFO - mozversion application_remotingname: firefox 05:12:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:12:06 INFO - mozversion application_vendor: Mozilla 05:12:06 INFO - mozversion application_version: 49.0a1 05:12:06 INFO - mozversion platform_buildid: 20160427030215 05:12:06 INFO - mozversion platform_changeset: ab0044bfa1df858919797bcd6a9aef76a668cd4a 05:12:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:12:06 INFO - mozversion platform_version: 49.0a1 05:12:06 INFO - using testdate: 1461759126 05:12:06 INFO - actual date: 1461759126 05:12:06 INFO - starting webserver on 'localhost:52330' 05:12:06 INFO - SUITE-START | Running 2 tests 05:12:06 INFO - TEST-START | dromaeo_css 05:12:06 INFO - operating with platform_type : mac_ 05:12:06 INFO - Initialising browser for dromaeo_css test... 05:12:06 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmp6zWzkf/profile http://localhost:52330/getInfo.html) 05:12:09 INFO - PROCESS | 1931 | __metrics Screen width/height:1600/1200 05:12:09 INFO - PROCESS | 1931 | colorDepth:24 05:12:09 INFO - PROCESS | 1931 | Browser inner width/height: 1024/689 05:12:09 INFO - PROCESS | 1931 | __metrics 05:12:09 INFO - PROCESS | 1931 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:12:09 ERROR - PROCESS | 1931 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:12:09 INFO - TEST-INFO | 1931: exit 0 05:12:09 INFO - Browser initialized. 05:12:09 INFO - Running cycle 1/1 for dromaeo_css test... 05:12:09 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmp6zWzkf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:12:21 INFO - PROCESS | 1934 | RSS: Main: 239439872 05:12:21 INFO - PROCESS | 1934 | 05:13:13 INFO - PROCESS | 1934 | 1461759193311 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:15:23 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52330/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52330/tests/dromaeo/cssquery-ext.html) 05:15:25 INFO - PROCESS | 1934 | RSS: Main: 393064448 05:15:25 INFO - PROCESS | 1934 | 05:18:28 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52330/tests/dromaeo/cssquery-ext.html (next: http://localhost:52330/tests/dromaeo/cssquery-jquery.html) 05:18:29 INFO - PROCESS | 1934 | RSS: Main: 377286656 05:18:29 INFO - PROCESS | 1934 | 05:21:31 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52330/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52330/tests/dromaeo/cssquery-mootools.html) 05:21:31 INFO - PROCESS | 1934 | RSS: Main: 376057856 05:21:31 INFO - PROCESS | 1934 | 05:24:34 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52330/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52330/tests/dromaeo/cssquery-prototype.html) 05:24:34 INFO - PROCESS | 1934 | RSS: Main: 379895808 05:24:34 INFO - PROCESS | 1934 | 05:27:38 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52330/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52330/tests/dromaeo/cssquery-yui.html) 05:27:38 INFO - PROCESS | 1934 | RSS: Main: 346284032 05:27:38 INFO - PROCESS | 1934 | 05:30:41 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:52330/tests/dromaeo/cssquery-yui.html (next: http://localhost:52330/tests/dromaeo/cssquery-dojo.html) 05:30:42 INFO - PROCESS | 1934 | RSS: Main: 385241088 05:30:42 INFO - PROCESS | 1934 | 05:30:42 INFO - PROCESS | 1934 | __start_tp_report 05:30:42 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 05:30:42 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 05:30:42 INFO - PROCESS | 1934 | |i|pagename|runs| 05:30:42 INFO - PROCESS | 1934 | |0;dojo.html;3364.966350336497;3402.5066365377006;3414.112330793994;3431.742619303553;3458.533098031765;5466.644668096581;5582.776688932443;5592.384837667853;5597.552195824334;5622.77508899644;5557.555395568355;5622.409646987069;5629.127485239795;5629.408912064226;5635.267415236024;5107.029664363778;5112.923306150392;5121.206213036985;5131.384233891919;5198.844034678966;5149.639525233235;5160.0969830279555;5188.896222075557;5201.531862132408;5226.980738755944;5109.514596113363;5158.613104017195;5197.532222100011;5222.738863056851;5238.947610523903;5204.271402003704;5215.087359712035;5221.269022336875;5223.869403264911;5284.603654725873;5032.471590482984;5035.823746168903;5069.797208111671;5139.434662187156;5144.974275128638;5437.211604317391;5506.394296627408;5517.36550296717;5524.806631767869;5528.253685752412;6015.488683461867;6036.094585812163;6050.6369617823075;6060.363661815534;6068.484178844804;6075.301340345873;6088.330283668793;6127;6127.080937859312;6205.596636218631;5706.9714651426475;5730.885382292414;5733.770649173986;5765.394633563499;5768.826935191951;6291.150694656254;6300.1494798202575;6301.40136687014;6305;6322.178116844781;5889.411058894162;5984;6087.9391206087375;6102.389761023951;6111.908321375183;6132.846678833065;6191.597546159485;6199.876002479925;6200.286966998763;6209.19280493542;6071.937410953065;6121.510279177655;6147.692615369214;6152.723127459275;6202.782902598387;6073.301570319381;6153.8307721532765;6185.84535386619;6199.318075011745;6200.441960223601;4871.439784424766;4908.165611845995;4919.065377578248;4922.089413458521;4929.285253638202;4433.7561434121435;4459.620932220732;4466.1067786442845;4475.060237350128;4481.395011673448;4736.976315118402;5560.944390556043;5643.830685079454;5644.6613203207935;5651.463111004448;6107.419795119546;6167.167432396569;6172.722227499773;6187.381261873777;6192.535559833031;5590;5595;5595.160725891067;5605.579581531401;5642.774289028475;5167.896642067138;5288.418273989858;5294.311739473844;5315.73421328932;5315.973420132952;6266.843328916813;6284.340144284784;6296.653684047421;6307.590006649553;6308.716107775161;6172.475339596184;6199.907001394892;6207.6275423474735;6217.378262173746;6223.782167624111;6193.690315484298;6245.156903817927;6303.968480157753;6310.905336420049;6321.589096708699;6129.414292638856;6136.3046386708;6143.784967526115;6144.508439324753;6158.0147176451555;6041.6375017499095;6196.0705894116245;6231.626102433868;6236.03341482071;6238.781642642486;5824.271966004249;5924.31870334917;5953.1367951647635;5970.731317090828;6112.419320164755;5927.31835838884;5976.671283079472;5990.550708696778;5994.100884867305;5996.160537524837;5743.7989670363;5884.029135192645;5884.08796636522;5904.173415721716;5932.970335148297;6026.939730602638;6062.363451837494;6066.211392518944;6100.572959892765;6108.961476548909;5717.3996730342715;5841.678707670949;5864.9413505864395;5878.794242201502;5882.647041177543 05:30:42 INFO - PROCESS | 1934 | |1;ext.html;509.775698692575;526.8867193553386;560.8138786466619;585.2187329914564;600.1747597054048;28291.575626365597;30219;31249.843750781216;37257.81371093144;38215;41352.58647413525;42132.368014479805;42153.15693686125;42260;42317.788411057976;9413.246940244764;9626.037396260388;9634.71095867125;9636.566354514029;9642.71071867845;8395.412321137523;8492.023417306997;11169.329840209573;11398.316101033923;11405.600803971862;11417.48621312043;11522.135839812006;11544.653660390202;11561.65315040546;11636.243644163145;8398.706045288385;10971.615993440231;11285.830712539318;11429.971302582766;11437.828432573519;11268.154888383404;11429.485673144645;11432.485538150802;11454.656360309204;11459.25514841541;30317.696823031707;36798.264034719155;37214;37259.44110838312;37323.25353492942;2612.477504499089;3307.669233076673;3419.350323438539;3516.507688923553;4048.878533643966;27396.315092122855;27825.304367390978;28521.714782852112;28635.14094577166;28680.856595716887;2677.531431999412;2778.2776478115634;2799.2162194585344;2828.123281782654;2919.576661384109;313222;316209;316291;316514.4174279137;316803;57418.42581574214;58736.412635873094;60428.69785651044;60454.69772651108;60482.697586511786;63507;63760.36239637637;63804;63908.68045659835;63924.68037659782;15875.682486350208;16417.425390111526;16421.261043253082;16441.424550140688;16442.75335869963;11205.663830084948;11424.942875285737;11443.427828608535;12653.873461265453;12709.618711438672;15653.747700183958;15885.602859928364;17463.47609571715;17956.55108622295;17994.820051799576;6603.240627327918;7506.211847756016;7683.385329173652;7756.379489640815;7888.1323054463965;789.7907054630527;936.2930987104693;1000.3797645459862;1060.3531845574194;1096.6216655253925;28259.71740282612;28460.573091403647;28918.42163156767;29088.12735617935;29121.271968200963;1000.8848982366975;1113.9610113645983;1134.4838098665089;1152.8731839497648;1167.177140116216;12559.434825432878;14577.92711036438;14680.66056506151;14844.62888427798;15592.376304947902;458947;460337;464337;465158;466869;46287.768561156976;55200.72399637976;56310;56402.153967690516;59452;1260.684828792802;1423.8220222472191;1461.1087236785766;1477.0546850015999;1479.615300022002;27947.441051178455;28019.299517512227;28516.42967140687;28624.713752863037;28732.13803585896;49196.50803491919;49261.75369123275;49579.75210123926;49621;49755.75122124511;417366;419503;420701;420716.896415516;421618;25608.719564022096;25804.838782255218;25945.09192178265;26272.474550509265;26334.604980925684;33712.831435842665;34253;37845.43231851469;40940.59059409368;44634;29484.705152949053;34840.82579587086;34854.477182842784;35532;35848.64151358557;35029.47455788216;35266.82366588151;35333.469997949534;35837.64162358343;35992.92021239367;34570.48144277788;34822.825885871425;35356.823215883756;35857;36095.45856812097;27542.724572754018;31190.22024449407;31728.04815855528;31909.840450797597;31914.20214494656;23377.41556461102;23647.763522364556;24045.879770601034;24139.275821725376;24183 05:30:42 INFO - PROCESS | 1934 | |2;jquery.html;400.48137661728066;402.46070265843764;403.5319029925287;410.56685197117065;421.90718042030744;14035.298235088256;19445;20210.6968395474;20234.69647955282;20243.392698219057;15384.384624615002;16183.352665893379;18886.433406997778;19312.71030934537;19737.901310493427;5693.715314234282;5861.53107751381;5915.526757859361;5915.822525324247;5917.5561832862495;5461.2354270402175;5704.8859022819515;5806.912896306559;5842.766289348442;5848;5798.159266906296;5836.766529338822;5838.065909454488;5841.649501029951;5850.122481627769;5478.287822583058;5732.31212254528;5734.5699072569305;5793.768249270024;5817.418258174191;5018;5029.3964724232965;5035.118854200537;5043.470435604246;5048.066107770074;22635.434114147112;24103.276901692978;24530.14144504934;24762.876185619138;24777;2087.332053742803;2092.4245832396214;2109.789021097893;2159.276642324823;2182.90176942038;13175.736485270241;14465.13209207451;14555.636109097251;14712.632184195374;14909.925450372679;1635.492997170881;1688.6875927953245;1702.4977631598708;1733.9826601733948;1767.2754170790042;103671.48164259206;106737;107040.46479767551;108063.91936080696;108537;11177.944110279397;11243.044341230923;11249.156313276537;11320.6037788677;11437.771244575062;14397.92801036009;14427.92786036063;14476.27618619065;14489.11641486621;14518.782218266735;7229.421646268391;7350.485466017328;8147.348212143014;8261.669533218725;8349.499030058216;8046.074701409296;8063.19368063189;8066.838663226703;8156.836863262702;8192;4102.856400025985;4367.606915377632;4482.708623939434;4520.570545798144;4531;1819.1359104425558;1822.4714832698369;1915.946229573729;1924.7978962209047;1933.574613585009;898.3217670658611;901.8241442918568;915.3866909170738;932.7528205025674;998.9960090109394;18039.188236529386;18677.346292879956;18898.771579847267;19197.616047678966;19316.76156089433;861.4314552395389;964.4936408385654;992.0525897767615;1003.8895721470633;1031.3966329697062;8757.737267881974;10114.645987390553;10147.64483243083;10149.797004059878;10182.949085254526;247530.48460396234;589838;590274;616311;633464;104911.95088049174;105538;106477;106741;108077;915.6703586708779;952.6284748947916;1012.119456073221;1014.06705830636;1034.3586976074732;16747.832521675115;16773.245203966097;17056.720745943854;17174.65650686954;17486.650266994333;37162.62837371592;45957;46475;47337;48288;64880.35119648932;65753.34246657472;67583.66208168928;68297.2925676862;68684;7059.2940705928995;7682.769516914502;7887.369010479262;7905.169957154416;7918.287354138155;9892.604295828087;10385;11776.352300623548;12045.277283363026;12046.096542759153;9984.500774961078;10091.798164036825;10177.338472999232;10277.17782577407;10439.634612788515;10246.487675616338;10265.538050787882;10300.896991029993;10423.68728938133;10594.417307048187;8905.376623636543;10236.87394386602;10470.581176752845;10601.1519078472;10683.51924163399;7342.889856652261;7717.1896950819355;7824.530528168328;8086;8180.181981801772;8800.823983520166;9786.608535658494;10323.32898361604;10686.0916822071;10719 05:30:42 INFO - PROCESS | 1934 | |3;mootools.html;1416.7520683880332;1617.9433719819806;1621.1894052973514;1888.886666799992;2023.9494012649673;7898.526088434691;8596.65613375465;8786.956065219681;8816.735497935073;8836.072212417692;8423.663053477869;8673.869891951626;8676.73969780906;8683.088275731052;8700.43447175933;2146.8067873891346;2228.6768418579377;2228.687983682286;2389.4982053768686;2430.1008626808016;2210.18223257395;2399.436132508859;2486.27897909606;2488.340589743719;2493.4514406830467;2022;2398.3164798032562;2426.1265944260053;2437.317551085699;2444.6577479152934;2133.9146434142617;2404.218628945591;2414;2417.1177520205174;2420.9636855447093;2362.0905951208906;2441.7680320369536;2446.1438496526252;2447.4370894694143;2449.265220433863;8606.44058136219;8677.609507572175;8692.261157801595;8704.825903481895;8715.172058654418;5181.533661970403;5385.380681221632;5416.783328666848;5423.7796495788525;5428.864278393072;3360.6975372216375;3488.7034602058857;3579.0294055933514;3602.7297952653657;3620;991.2763682511735;999.6251405722854;1001.664442411793;1020.5356562763999;1021.5607288865784;11808.46861891217;11818.409079545989;11856.644300670994;11862.288262704265;11894.58368957091;4776.617870570346;4808.797800549863;4911.9754401227765;4912.9508704913205;4933.555979961749;10091.192704583616;10094.747631309276;10096.747581310527;10135.949320253352;10137.64518241858;5391.649542779707;5404.270423492778;5416.647917885325;5463.207834864002;5469.124940009579;4827.493113223131;4891.437484689235;4937.481564435682;4957.157283261854;4997.975010124999;3899.025243689078;3909.354956432214;3953.288408086572;3989.0625702959783;3997.520297564311;1335.1455068756004;1340.3030424179371;1343.1135450602555;1350.162899002605;1359.286374653295;795.8368534450424;826.338928856912;844.3456321351002;852.9616167272487;934.4626839567276;5639.718014099279;5697.686627235542;5718.599698021098;5818.4763371296785;5978.043513037893;392.8487532300085;461.87067621066154;475.6123759136293;482.27899290560833;960.0015983377349;3292.3250733599552;3841.4429907663234;3892.9416058759143;3913.882583522499;3921.8039098044983;10412.791744165073;10514.842277365846;10775.94612026935;10895.346279223271;10907.400092994803;10432.321899076536;10736.248462607542;10866.565337386415;10871.021608055155;10896.237263391646;8020.879686804703;8251.917480825236;8259.297959673375;8345.791355216046;8382.87425688615;5684.289463817023;5774.82675519735;5794.5364370851075;5876.265466816648;5899.46904778572;9955.452450115314;10131.848022279524;10150.949245253727;10222.693319200436;10239.641612543524;9987.151092156957;10027.448490333101;10183.949080254552;10388.324758890649;10446.947765261126;7098.112735908012;7131.215566287808;7230.060092187982;7236.095488063992;7236.529625574321;8194.54929978833;8289.171082891662;8309.875351869849;8314.293285070837;8341.124181960808;6669.633170175687;7214.314640109181;7470.402367810453;7558.58427786455;7582.848343033219;7396.519226250277;7428.33145016951;7498.887516687145;7551.282628150317;7564.243575642392;6931.826704332432;7062.505624606434;7086.220515743161;7113.288671133052;7120.893186602309;4806.206975848946;4813.350197723263;4875.219964805614;4938.407391113089;5063.974680126576;4895.412550493964;4946.35697359341;5004.649674522749;5012.899742005213;5190.299309593157 05:30:42 INFO - PROCESS | 1934 | |4;prototype.html;318.8119009784227;322.75470642311853;330.07414203160135;331.6236072058215;337.50049926109375;5362.487570641227;5422.755975981076;5424.972875135623;5427.0773968425365;5428.864278393042;4430.756308403044;4772.713637181771;4932.778024988876;5205.791768329263;5381.569474442045;3195.7603179761495;3931.528216614003;4032.3749818778138;4035.4148648445957;4059.776712280823;3201.6798320167914;4164.97917510412;4168.416421700964;4175.832966681329;4208.169446290874;3595.370810108234;3879.243547508237;3893.5911729268446;4061.97969010156;4158;3941.487606611151;3988.581198974095;4122.505299364096;4168.353905144692;4169.228692691847;3381.9684996076185;3531.611522732497;3544.468329750532;3586.318599466093;3622.3479773640734;4500.909981800379;5101.081805275049;5162.303089082963;5182.7408629568745;5203.43896830953;3972.185701931126;4409.4488188976375;4565.885852853672;4730.5032971538185;4753.881152971168;4767.332573439721;5198.766055527511;5632.155176723524;5640.5487560995025;5698.715064246771;3602.495650608917;3994.1826468956556;4007.3788562772816;4026.396040593905;4054.412110243998;4667.883302917387;5048.823291184827;5236.764345604457;5974.193483879664;6008.879822403571;3644.2347107107803;3983.820728067244;4092.0767239397437;4097.221527909687;4133.834646614162;3958.8218530166214;4141.213169497786;4145.97927010369;4175.937360939588;4175.958240417618;3970.79620810453;4800.447948485899;4809.374781278481;4820.156472617278;4823.614110871192;4496.662750293741;4779.016708066183;5426.945730542723;5767.221425107557;5770.394108618619;1340.7653660609353;1500.8499150085122;1511.7581187010026;1516.9696606067816;1523.803814006022;940.8823897012874;985.4875464758286;1020.2399212586671;1027.2039169643585;1028.3572767020612;5442.727863606804;5789.2763404574425;5862;5887.8233652990475;5902.970485147547;5378.273933019071;6346.873062538723;6403.551751377358;6433.356664333529;6440.098386225932;4589.632829373642;5224.712640804716;5578.30271216098;5800.4489573490455;5846.766129354778;3372.8313584321177;3380.560527131506;3400.778949368283;3403.982980085084;3407.3866703993517;5924.940750592439;6245.375462453809;6707.698153583003;6727.275453441364;6736.629485378351;6099.68212079407;6134.77304539094;6139.662318572432;6149.2313460817395;6192.195014648067;5368.489993450616;5524.806631767869;5918.704064796829;6104.6337219766065;6330.974088291702;5249.580033597379;5631.324241091095;5669.281930781706;5946.818452128091;6013.879722405615;5557.693941923564;5741.6172288501475;5894.351621321565;5898.882022359443;5900;5933.554983376178;6158.938410615837;6184.783532576518;6205.65868877198;6212.751489940351;3158.289384888382;3614.8554057838446;3953.723239373216;3969.980150099231;4007.499062617173;5176.7152806594495;5978.760849565969;5983.491403230774;5998.310194327708;6004.939950600438;5126.871828204325;5484.095124304445;5738.368779434342;5800.883982320245;5842.370933080216;5000.374953130859;5506.2291279221745;5621.7189140542;5820.417958204146;5827.5629327801435;4700.529947005272;5598.552115830642;5608.989572455714;5612.691301978431;5620.213170156099;4984.900301994012;5107.32755567106;5461.808836690697;5489.39616642177;5701.859628074319;4562.680612357103;4801.4718380977365;4955.554000140005;5644.209810626434;5667.3482549507335 05:30:42 INFO - PROCESS | 1934 | |5;yui.html;736.9852602947941;752.059925093633;755.7392699518667;767.842592268585;791.5923299500756;14500.057496262736;14929.552113436614;14996.700065998688;15024;16232.269547870344;15822.604434889134;15952.920235398837;15955.680886382266;15959.281832317545;15967.680646387065;734.9522281051741;740.2153717059933;934.7055677461578;965.1072757699135;965.835807912653;685.5715178013742;943.2406912435487;944.0182210501071;949.2263804998938;949.9050094990515;982.882054153499;988.9851652225223;990.8315586350337;991.0386924683082;991.6479649724353;773.3928865840288;775.9573223472706;785.3873978296938;980.294188184506;984.4634674102631;594.0406243916087;726.1467775363978;748.3451979517922;750.3734381791211;751.751921865783;14996.47512337074;15094.018888772305;15096.54710358691;15123.546293611209;15147;10229.846552301722;12022;12158.209716368408;12518.74962500745;12522.060845436628;8549.572521373906;9539.046095390475;10100.646477373257;10107.191424686007;10362.896371036268;3404;3410.38613049651;3428.708559772422;3451.3097380523996;3530.3998320285614;76427.61786191033;76451;76767.61616191939;78523.60738196272;79052.60473697653;8274.524699626172;8930.374873758905;8976;9198.172164505228;9198.862017069749;20680.793192068188;21795.128194872028;21908;21949.341519754435;21978.45053873666;7292.927070729331;7408.777736667908;7657.34912532441;7714.614269286513;7801.570913599693;3708.42519409492;3727.9068023299633;3755.7746535207975;3885.8277488210474;3917.216556688674;3481.060113769268;3548.3435564420665;3549.290141971616;3551.8224088795455;3557.9822100889846;629.7827249598881;643.0547095769211;643.46358174867;646.2826262848234;650.5543702563759;511.23825500005466;533.6531254684486;609.3723464831231;613.9263288405419;620.6276234259499;6870.896936545956;7683.8474228865;8024.398170137263;8152.225538573826;8294.58527073644;577.3389469057905;590.8965930962064;595.3540408656656;603.5292471871946;613.9341117140879;3640.4357324614725;3741.551013878352;3782;3786.1291902861944;3825.3114439401174;88921;89070;89569;91048.54475727578;91775.5411222953;75114.24885751073;75555.62222188964;76927.61536192395;77089;77429.83855242176;54727.72636136953;63621.68189159025;64214.678926606;64413.67793161098;65761.0135847953;7138.964305178649;7703.343781087093;8042.356611470951;8275.089740128702;8320.41757077023;38100.856974290815;39566.604333957446;41107;41141.1771764569;42715.718528444195;54470.91058178735;54473;54890.451095490134;55111.72444137753;55892;10685.57257709683;10735.838962415726;10738.516766745672;10939.343639381663;11206.775864482828;11745.588904388305;11876.406179691154;11878.58424955157;12302.261864288172;12401.069919756235;9502.524873756147;10993.61522346714;11233.269837460539;11953.462094205608;12299.815502767644;11123.944380278046;11217.943910280397;11295.3787541686;11439.313641181556;11471.483783229942;10928.50821713009;11096.167787415814;11195.160362972647;11398.15888915991;11451.25566838153;9389.154976052188;9570.33007689455;9652.372595781251;9852.901470985486;9967.601295948081;9452.054794520493;9489.383190092622;9512.952435238056;9565.091316324939;9595.808083838423 05:30:42 INFO - PROCESS | 1934 | __end_tp_report 05:30:42 INFO - PROCESS | 1934 | __start_cc_report 05:30:42 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,1502 05:30:42 INFO - PROCESS | 1934 | __end_cc_report 05:30:42 INFO - PROCESS | 1934 | __startTimestamp1461760242248__endTimestamp 05:30:42 INFO - PROCESS | 1934 | 05:30:42 INFO - PROCESS | 1934 | ------- Summary: start ------- 05:30:42 INFO - PROCESS | 1934 | Number of tests: 6 05:30:42 INFO - PROCESS | 1934 | 05:30:42 INFO - PROCESS | 1934 | [#0] dojo.html Cycles:165 Average:5677.25 Median:5884.03 stddev:621.09 (10.6%) stddev-sans-first:595.92 05:30:42 INFO - PROCESS | 1934 | Values: 3365.0 3402.5 3414.1 3431.7 3458.5 5466.6 5582.8 5592.4 5597.6 5622.8 5557.6 5622.4 5629.1 5629.4 5635.3 5107.0 5112.9 5121.2 5131.4 5198.8 5149.6 5160.1 5188.9 5201.5 5227.0 5109.5 5158.6 5197.5 5222.7 5238.9 5204.3 5215.1 5221.3 5223.9 5284.6 5032.5 5035.8 5069.8 5139.4 5145.0 5437.2 5506.4 5517.4 5524.8 5528.3 6015.5 6036.1 6050.6 6060.4 6068.5 6075.3 6088.3 6127.0 6127.1 6205.6 5707.0 5730.9 5733.8 5765.4 5768.8 6291.2 6300.1 6301.4 6305.0 6322.2 5889.4 5984.0 6087.9 6102.4 6111.9 6132.8 6191.6 6199.9 6200.3 6209.2 6071.9 6121.5 6147.7 6152.7 6202.8 6073.3 6153.8 6185.8 6199.3 6200.4 4871.4 4908.2 4919.1 4922.1 4929.3 4433.8 4459.6 4466.1 4475.1 4481.4 4737.0 5560.9 5643.8 5644.7 5651.5 6107.4 6167.2 6172.7 6187.4 6192.5 5590.0 5595.0 5595.2 5605.6 5642.8 5167.9 5288.4 5294.3 5315.7 5316.0 6266.8 6284.3 6296.7 6307.6 6308.7 6172.5 6199.9 6207.6 6217.4 6223.8 6193.7 6245.2 6304.0 6310.9 6321.6 6129.4 6136.3 6143.8 6144.5 6158.0 6041.6 6196.1 6231.6 6236.0 6238.8 5824.3 5924.3 5953.1 5970.7 6112.4 5927.3 5976.7 5990.6 5994.1 5996.2 5743.8 5884.0 5884.1 5904.2 5933.0 6026.9 6062.4 6066.2 6100.6 6109.0 5717.4 5841.7 5864.9 5878.8 5882.6 05:30:42 INFO - PROCESS | 1934 | 05:30:42 INFO - PROCESS | 1934 | [#1] ext.html Cycles:180 Average:54927.45 Median:316402.71 stddev:107094.70 (33.8%) stddev-sans-first:107317.19 05:30:42 INFO - PROCESS | 1934 | Values: 509.8 526.9 560.8 585.2 600.2 28291.6 30219.0 31249.8 37257.8 38215.0 41352.6 42132.4 42153.2 42260.0 42317.8 9413.2 9626.0 9634.7 9636.6 9642.7 8395.4 8492.0 11169.3 11398.3 11405.6 11417.5 11522.1 11544.7 11561.7 11636.2 8398.7 10971.6 11285.8 11430.0 11437.8 11268.2 11429.5 11432.5 11454.7 11459.3 30317.7 36798.3 37214.0 37259.4 37323.3 2612.5 3307.7 3419.4 3516.5 4048.9 27396.3 27825.3 28521.7 28635.1 28680.9 2677.5 2778.3 2799.2 2828.1 2919.6 313222.0 316209.0 316291.0 316514.4 316803.0 57418.4 58736.4 60428.7 60454.7 60482.7 63507.0 63760.4 63804.0 63908.7 63924.7 15875.7 16417.4 16421.3 16441.4 16442.8 11205.7 11424.9 11443.4 12653.9 12709.6 15653.7 15885.6 17463.5 17956.6 17994.8 6603.2 7506.2 7683.4 7756.4 7888.1 789.8 936.3 1000.4 1060.4 1096.6 28259.7 28460.6 28918.4 29088.1 29121.3 1000.9 1114.0 1134.5 1152.9 1167.2 12559.4 14577.9 14680.7 14844.6 15592.4 458947.0 460337.0 464337.0 465158.0 466869.0 46287.8 55200.7 56310.0 56402.2 59452.0 1260.7 1423.8 1461.1 1477.1 1479.6 27947.4 28019.3 28516.4 28624.7 28732.1 49196.5 49261.8 49579.8 49621.0 49755.8 417366.0 419503.0 420701.0 420716.9 421618.0 25608.7 25804.8 25945.1 26272.5 26334.6 33712.8 34253.0 37845.4 40940.6 44634.0 29484.7 34840.8 34854.5 35532.0 35848.6 35029.5 35266.8 35333.5 35837.6 35992.9 34570.5 34822.8 35356.8 35857.0 36095.5 27542.7 31190.2 31728.0 31909.8 31914.2 23377.4 23647.8 24045.9 24139.3 24183.0 05:30:42 INFO - PROCESS | 1934 | 05:30:42 INFO - PROCESS | 1934 | [#2] jquery.html Cycles:180 Average:31460.73 Median:24316.71 stddev:92288.73 (379.5%) stddev-sans-first:92518.16 05:30:42 INFO - PROCESS | 1934 | Values: 400.5 402.5 403.5 410.6 421.9 14035.3 19445.0 20210.7 20234.7 20243.4 15384.4 16183.4 18886.4 19312.7 19737.9 5693.7 5861.5 5915.5 5915.8 5917.6 5461.2 5704.9 5806.9 5842.8 5848.0 5798.2 5836.8 5838.1 5841.6 5850.1 5478.3 5732.3 5734.6 5793.8 5817.4 5018.0 5029.4 5035.1 5043.5 5048.1 22635.4 24103.3 24530.1 24762.9 24777.0 2087.3 2092.4 2109.8 2159.3 2182.9 13175.7 14465.1 14555.6 14712.6 14909.9 1635.5 1688.7 1702.5 1734.0 1767.3 103671.5 106737.0 107040.5 108063.9 108537.0 11177.9 11243.0 11249.2 11320.6 11437.8 14397.9 14427.9 14476.3 14489.1 14518.8 7229.4 7350.5 8147.3 8261.7 8349.5 8046.1 8063.2 8066.8 8156.8 8192.0 4102.9 4367.6 4482.7 4520.6 4531.0 1819.1 1822.5 1915.9 1924.8 1933.6 898.3 901.8 915.4 932.8 999.0 18039.2 18677.3 18898.8 19197.6 19316.8 861.4 964.5 992.1 1003.9 1031.4 8757.7 10114.6 10147.6 10149.8 10182.9 247530.5 589838.0 590274.0 616311.0 633464.0 104912.0 105538.0 106477.0 106741.0 108077.0 915.7 952.6 1012.1 1014.1 1034.4 16747.8 16773.2 17056.7 17174.7 17486.7 37162.6 45957.0 46475.0 47337.0 48288.0 64880.4 65753.3 67583.7 68297.3 68684.0 7059.3 7682.8 7887.4 7905.2 7918.3 9892.6 10385.0 11776.4 12045.3 12046.1 9984.5 10091.8 10177.3 10277.2 10439.6 10246.5 10265.5 10300.9 10423.7 10594.4 8905.4 10236.9 10470.6 10601.2 10683.5 7342.9 7717.2 7824.5 8086.0 8180.2 8800.8 9786.6 10323.3 10686.1 10719.0 05:30:42 INFO - PROCESS | 1934 | 05:30:42 INFO - PROCESS | 1934 | [#3] mootools.html Cycles:180 Average:5718.74 Median:4883.33 stddev:3231.98 (66.2%) stddev-sans-first:3224.88 05:30:42 INFO - PROCESS | 1934 | Values: 1416.8 1617.9 1621.2 1888.9 2023.9 7898.5 8596.7 8787.0 8816.7 8836.1 8423.7 8673.9 8676.7 8683.1 8700.4 2146.8 2228.7 2228.7 2389.5 2430.1 2210.2 2399.4 2486.3 2488.3 2493.5 2022.0 2398.3 2426.1 2437.3 2444.7 2133.9 2404.2 2414.0 2417.1 2421.0 2362.1 2441.8 2446.1 2447.4 2449.3 8606.4 8677.6 8692.3 8704.8 8715.2 5181.5 5385.4 5416.8 5423.8 5428.9 3360.7 3488.7 3579.0 3602.7 3620.0 991.3 999.6 1001.7 1020.5 1021.6 11808.5 11818.4 11856.6 11862.3 11894.6 4776.6 4808.8 4912.0 4913.0 4933.6 10091.2 10094.7 10096.7 10135.9 10137.6 5391.6 5404.3 5416.6 5463.2 5469.1 4827.5 4891.4 4937.5 4957.2 4998.0 3899.0 3909.4 3953.3 3989.1 3997.5 1335.1 1340.3 1343.1 1350.2 1359.3 795.8 826.3 844.3 853.0 934.5 5639.7 5697.7 5718.6 5818.5 5978.0 392.8 461.9 475.6 482.3 960.0 3292.3 3841.4 3892.9 3913.9 3921.8 10412.8 10514.8 10775.9 10895.3 10907.4 10432.3 10736.2 10866.6 10871.0 10896.2 8020.9 8251.9 8259.3 8345.8 8382.9 5684.3 5774.8 5794.5 5876.3 5899.5 9955.5 10131.8 10150.9 10222.7 10239.6 9987.2 10027.4 10183.9 10388.3 10446.9 7098.1 7131.2 7230.1 7236.1 7236.5 8194.5 8289.2 8309.9 8314.3 8341.1 6669.6 7214.3 7470.4 7558.6 7582.8 7396.5 7428.3 7498.9 7551.3 7564.2 6931.8 7062.5 7086.2 7113.3 7120.9 4806.2 4813.4 4875.2 4938.4 5064.0 4895.4 4946.4 5004.6 5012.9 5190.3 05:30:42 INFO - PROCESS | 1934 | 05:30:42 INFO - PROCESS | 1934 | [#4] prototype.html Cycles:180 Average:4674.06 Median:5117.10 stddev:1441.75 (28.2%) stddev-sans-first:1408.25 05:30:42 INFO - PROCESS | 1934 | Values: 318.8 322.8 330.1 331.6 337.5 5362.5 5422.8 5425.0 5427.1 5428.9 4430.8 4772.7 4932.8 5205.8 5381.6 3195.8 3931.5 4032.4 4035.4 4059.8 3201.7 4165.0 4168.4 4175.8 4208.2 3595.4 3879.2 3893.6 4062.0 4158.0 3941.5 3988.6 4122.5 4168.4 4169.2 3382.0 3531.6 3544.5 3586.3 3622.3 4500.9 5101.1 5162.3 5182.7 5203.4 3972.2 4409.4 4565.9 4730.5 4753.9 4767.3 5198.8 5632.2 5640.5 5698.7 3602.5 3994.2 4007.4 4026.4 4054.4 4667.9 5048.8 5236.8 5974.2 6008.9 3644.2 3983.8 4092.1 4097.2 4133.8 3958.8 4141.2 4146.0 4175.9 4176.0 3970.8 4800.4 4809.4 4820.2 4823.6 4496.7 4779.0 5426.9 5767.2 5770.4 1340.8 1500.8 1511.8 1517.0 1523.8 940.9 985.5 1020.2 1027.2 1028.4 5442.7 5789.3 5862.0 5887.8 5903.0 5378.3 6346.9 6403.6 6433.4 6440.1 4589.6 5224.7 5578.3 5800.4 5846.8 3372.8 3380.6 3400.8 3404.0 3407.4 5924.9 6245.4 6707.7 6727.3 6736.6 6099.7 6134.8 6139.7 6149.2 6192.2 5368.5 5524.8 5918.7 6104.6 6331.0 5249.6 5631.3 5669.3 5946.8 6013.9 5557.7 5741.6 5894.4 5898.9 5900.0 5933.6 6158.9 6184.8 6205.7 6212.8 3158.3 3614.9 3953.7 3970.0 4007.5 5176.7 5978.8 5983.5 5998.3 6004.9 5126.9 5484.1 5738.4 5800.9 5842.4 5000.4 5506.2 5621.7 5820.4 5827.6 4700.5 5598.6 5609.0 5612.7 5620.2 4984.9 5107.3 5461.8 5489.4 5701.9 4562.7 4801.5 4955.6 5644.2 5667.3 05:30:42 INFO - PROCESS | 1934 | 05:30:42 INFO - PROCESS | 1934 | [#5] yui.html Cycles:180 Average:17247.04 Median:611.65 stddev:23874.62 (3903.3%) stddev-sans-first:23909.41 05:30:42 INFO - PROCESS | 1934 | Values: 737.0 752.1 755.7 767.8 791.6 14500.1 14929.6 14996.7 15024.0 16232.3 15822.6 15952.9 15955.7 15959.3 15967.7 735.0 740.2 934.7 965.1 965.8 685.6 943.2 944.0 949.2 949.9 982.9 989.0 990.8 991.0 991.6 773.4 776.0 785.4 980.3 984.5 594.0 726.1 748.3 750.4 751.8 14996.5 15094.0 15096.5 15123.5 15147.0 10229.8 12022.0 12158.2 12518.7 12522.1 8549.6 9539.0 10100.6 10107.2 10362.9 3404.0 3410.4 3428.7 3451.3 3530.4 76427.6 76451.0 76767.6 78523.6 79052.6 8274.5 8930.4 8976.0 9198.2 9198.9 20680.8 21795.1 21908.0 21949.3 21978.5 7292.9 7408.8 7657.3 7714.6 7801.6 3708.4 3727.9 3755.8 3885.8 3917.2 3481.1 3548.3 3549.3 3551.8 3558.0 629.8 643.1 643.5 646.3 650.6 511.2 533.7 609.4 613.9 620.6 6870.9 7683.8 8024.4 8152.2 8294.6 577.3 590.9 595.4 603.5 613.9 3640.4 3741.6 3782.0 3786.1 3825.3 88921.0 89070.0 89569.0 91048.5 91775.5 75114.2 75555.6 76927.6 77089.0 77429.8 54727.7 63621.7 64214.7 64413.7 65761.0 7139.0 7703.3 8042.4 8275.1 8320.4 38100.9 39566.6 41107.0 41141.2 42715.7 54470.9 54473.0 54890.5 55111.7 55892.0 10685.6 10735.8 10738.5 10939.3 11206.8 11745.6 11876.4 11878.6 12302.3 12401.1 9502.5 10993.6 11233.3 11953.5 12299.8 11123.9 11217.9 11295.4 11439.3 11471.5 10928.5 11096.2 11195.2 11398.2 11451.3 9389.2 9570.3 9652.4 9852.9 9967.6 9452.1 9489.4 9513.0 9565.1 9595.8 05:30:42 INFO - PROCESS | 1934 | -------- Summary: end -------- 05:30:42 INFO - PROCESS | 1934 | 05:30:42 INFO - TEST-INFO | 1934: exit 0 05:30:42 INFO - TEST-OK | dromaeo_css | took 1116002ms 05:30:42 INFO - TEST-START | kraken 05:30:42 INFO - operating with platform_type : mac_ 05:30:42 INFO - Initialising browser for kraken test... 05:30:42 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmp_o1EPz/profile http://localhost:52330/getInfo.html) 05:30:44 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 05:30:44 INFO - PROCESS | 1939 | colorDepth:24 05:30:44 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 05:30:44 INFO - PROCESS | 1939 | __metrics 05:30:45 INFO - PROCESS | 1939 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:30:45 ERROR - PROCESS | 1939 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:30:45 INFO - TEST-INFO | 1939: exit 0 05:30:45 INFO - Browser initialized. 05:30:45 INFO - Running cycle 1/1 for kraken test... 05:30:45 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmp_o1EPz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:57 INFO - PROCESS | 1942 | RSS: Main: 239280128 05:30:57 INFO - PROCESS | 1942 | 05:31:00 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52330/tests/kraken/driver.html?testName=audio-beat-detection) 05:31:01 INFO - PROCESS | 1942 | RSS: Main: 286998528 05:31:01 INFO - PROCESS | 1942 | 05:31:04 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52330/tests/kraken/driver.html?testName=audio-dft) 05:31:05 INFO - PROCESS | 1942 | RSS: Main: 299839488 05:31:05 INFO - PROCESS | 1942 | 05:31:08 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52330/tests/kraken/driver.html?testName=audio-fft) 05:31:09 INFO - PROCESS | 1942 | RSS: Main: 389300224 05:31:09 INFO - PROCESS | 1942 | 05:31:11 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52330/tests/kraken/driver.html?testName=audio-oscillator) 05:31:11 INFO - PROCESS | 1942 | RSS: Main: 287662080 05:31:11 INFO - PROCESS | 1942 | 05:31:13 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52330/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:31:14 INFO - PROCESS | 1942 | RSS: Main: 292036608 05:31:14 INFO - PROCESS | 1942 | 05:31:17 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52330/tests/kraken/driver.html?testName=imaging-darkroom) 05:31:17 INFO - PROCESS | 1942 | RSS: Main: 329433088 05:31:17 INFO - PROCESS | 1942 | 05:31:20 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52330/tests/kraken/driver.html?testName=imaging-desaturate) 05:31:21 INFO - PROCESS | 1942 | RSS: Main: 331444224 05:31:21 INFO - PROCESS | 1942 | 05:31:24 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52330/tests/kraken/driver.html?testName=json-parse-financial) 05:31:24 INFO - PROCESS | 1942 | RSS: Main: 330100736 05:31:24 INFO - PROCESS | 1942 | 05:31:26 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52330/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:31:27 INFO - PROCESS | 1942 | RSS: Main: 322060288 05:31:27 INFO - PROCESS | 1942 | 05:31:29 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52330/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:31:29 INFO - PROCESS | 1942 | RSS: Main: 328388608 05:31:29 INFO - PROCESS | 1942 | 05:31:31 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52330/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:31:32 INFO - PROCESS | 1942 | RSS: Main: 330735616 05:31:32 INFO - PROCESS | 1942 | 05:31:35 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52330/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:31:35 INFO - PROCESS | 1942 | RSS: Main: 335032320 05:31:35 INFO - PROCESS | 1942 | 05:31:39 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52330/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:31:39 INFO - PROCESS | 1942 | RSS: Main: 332619776 05:31:39 INFO - PROCESS | 1942 | 05:31:41 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52330/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52330/tests/kraken/driver.html?testName=ai-astar) 05:31:42 INFO - PROCESS | 1942 | RSS: Main: 396886016 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | __start_tp_report 05:31:42 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 05:31:42 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 05:31:42 INFO - PROCESS | 1942 | |i|pagename|runs| 05:31:42 INFO - PROCESS | 1942 | |0;ai-astar;132;112;108;111;113;112;112;117;152;155 05:31:42 INFO - PROCESS | 1942 | |1;audio-beat-detection;156;134;175;208;119;144;143;122;167;114 05:31:42 INFO - PROCESS | 1942 | |2;audio-dft;190;189;186;182;186;184;209;209;183;185 05:31:42 INFO - PROCESS | 1942 | |3;audio-fft;79;74;79;150;56;55;65;68;128;57 05:31:42 INFO - PROCESS | 1942 | |4;audio-oscillator;90;90;143;80;79;84;130;80;78;135 05:31:42 INFO - PROCESS | 1942 | |5;imaging-gaussian-blur;86;83;86;82;85;83;86;82;86;83 05:31:42 INFO - PROCESS | 1942 | |6;imaging-darkroom;91;92;92;93;91;110;148;108;91;92 05:31:42 INFO - PROCESS | 1942 | |7;imaging-desaturate;94;85;72;126;138;86;85;84;84;87 05:31:42 INFO - PROCESS | 1942 | |8;json-parse-financial;61;61;60;58;58;58;61;92;89;56 05:31:42 INFO - PROCESS | 1942 | |9;json-stringify-tinderbox;63;67;67;80;74;72;67;68;68;70 05:31:42 INFO - PROCESS | 1942 | |10;stanford-crypto-aes;83;82;83;82;82;82;82;81;83;83 05:31:42 INFO - PROCESS | 1942 | |11;stanford-crypto-ccm;143;157;138;137;139;139;141;140;183;135 05:31:42 INFO - PROCESS | 1942 | |12;stanford-crypto-pbkdf2;199;200;222;281;237;198;204;199;194;198 05:31:42 INFO - PROCESS | 1942 | |13;stanford-crypto-sha256-iterative;71;68;72;70;71;72;69;72;80;102 05:31:42 INFO - PROCESS | 1942 | __end_tp_report 05:31:42 INFO - PROCESS | 1942 | __start_cc_report 05:31:42 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,1988 05:31:42 INFO - PROCESS | 1942 | __end_cc_report 05:31:42 INFO - PROCESS | 1942 | __startTimestamp1461760301997__endTimestamp 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | ------- Summary: start ------- 05:31:42 INFO - PROCESS | 1942 | Number of tests: 14 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#0] ai-astar Cycles:10 Average:122.40 Median:115.00 stddev:17.67 (15.4%) stddev-sans-first:18.40 05:31:42 INFO - PROCESS | 1942 | Values: 132.0 112.0 108.0 111.0 113.0 112.0 112.0 117.0 152.0 155.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:150.00 stddev:29.18 (19.5%) stddev-sans-first:30.81 05:31:42 INFO - PROCESS | 1942 | Values: 156.0 134.0 175.0 208.0 119.0 144.0 143.0 122.0 167.0 114.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#2] audio-dft Cycles:10 Average:190.30 Median:187.50 stddev:10.15 (5.4%) stddev-sans-first:10.77 05:31:42 INFO - PROCESS | 1942 | Values: 190.0 189.0 186.0 182.0 186.0 184.0 209.0 209.0 183.0 185.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#3] audio-fft Cycles:10 Average:81.10 Median:66.50 stddev:32.20 (48.4%) stddev-sans-first:34.14 05:31:42 INFO - PROCESS | 1942 | Values: 79.0 74.0 79.0 150.0 56.0 55.0 65.0 68.0 128.0 57.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#4] audio-oscillator Cycles:10 Average:98.90 Median:80.00 stddev:26.13 (32.7%) stddev-sans-first:27.51 05:31:42 INFO - PROCESS | 1942 | Values: 90.0 90.0 143.0 80.0 79.0 84.0 130.0 80.0 78.0 135.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#5] imaging-gaussian-blur Cycles:10 Average:84.20 Median:85.50 stddev:1.75 (2.0%) stddev-sans-first:1.73 05:31:42 INFO - PROCESS | 1942 | Values: 86.0 83.0 86.0 82.0 85.0 83.0 86.0 82.0 86.0 83.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:91.50 stddev:18.09 (19.8%) stddev-sans-first:18.84 05:31:42 INFO - PROCESS | 1942 | Values: 91.0 92.0 92.0 93.0 91.0 110.0 148.0 108.0 91.0 92.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:85.00 stddev:20.87 (24.5%) stddev-sans-first:22.13 05:31:42 INFO - PROCESS | 1942 | Values: 94.0 85.0 72.0 126.0 138.0 86.0 85.0 84.0 84.0 87.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#8] json-parse-financial Cycles:10 Average:65.40 Median:61.00 stddev:13.35 (21.9%) stddev-sans-first:14.07 05:31:42 INFO - PROCESS | 1942 | Values: 61.0 61.0 60.0 58.0 58.0 58.0 61.0 92.0 89.0 56.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:69.00 stddev:4.74 (6.9%) stddev-sans-first:4.39 05:31:42 INFO - PROCESS | 1942 | Values: 63.0 67.0 67.0 80.0 74.0 72.0 67.0 68.0 68.0 70.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:82.50 stddev:0.67 (0.8%) stddev-sans-first:0.67 05:31:42 INFO - PROCESS | 1942 | Values: 83.0 82.0 83.0 82.0 82.0 82.0 82.0 81.0 83.0 83.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:140.50 stddev:14.60 (10.4%) stddev-sans-first:15.46 05:31:42 INFO - PROCESS | 1942 | Values: 143.0 157.0 138.0 137.0 139.0 139.0 141.0 140.0 183.0 135.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:213.20 Median:202.00 stddev:27.35 (13.5%) stddev-sans-first:28.53 05:31:42 INFO - PROCESS | 1942 | Values: 199.0 200.0 222.0 281.0 237.0 198.0 204.0 199.0 194.0 198.0 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - PROCESS | 1942 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:71.50 stddev:10.12 (14.2%) stddev-sans-first:10.65 05:31:42 INFO - PROCESS | 1942 | Values: 71.0 68.0 72.0 70.0 71.0 72.0 69.0 72.0 80.0 102.0 05:31:42 INFO - PROCESS | 1942 | -------- Summary: end -------- 05:31:42 INFO - PROCESS | 1942 | 05:31:42 INFO - TEST-INFO | 1942: exit 0 05:31:42 INFO - TEST-OK | kraken | took 60015ms 05:31:42 INFO - SUITE-END | took 1176s 05:31:43 INFO - Completed test suite (00:19:36) 05:31:43 INFO - javascript benchmark 05:31:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3364.966350336497, 3402.5066365377006, 3414.112330793994, 3431.742619303553, 3458.533098031765, 5466.644668096581, 5582.776688932443, 5592.384837667853, 5597.552195824334, 5622.77508899644, 5557.555395568355, 5622.409646987069, 5629.127485239795, 5629.408912064226, 5635.267415236024, 5107.029664363778, 5112.923306150392, 5121.206213036985, 5131.384233891919, 5198.844034678966, 5149.639525233235, 5160.0969830279555, 5188.896222075557, 5201.531862132408, 5226.980738755944, 5109.514596113363, 5158.613104017195, 5197.532222100011, 5222.738863056851, 5238.947610523903, 5204.271402003704, 5215.087359712035, 5221.269022336875, 5223.869403264911, 5284.603654725873, 5032.471590482984, 5035.823746168903, 5069.797208111671, 5139.434662187156, 5144.974275128638, 5437.211604317391, 5506.394296627408, 5517.36550296717, 5524.806631767869, 5528.253685752412, 6015.488683461867, 6036.094585812163, 6050.6369617823075, 6060.363661815534, 6068.484178844804, 6075.301340345873, 6088.330283668793, 6127.0, 6127.080937859312, 6205.596636218631, 5706.9714651426475, 5730.885382292414, 5733.770649173986, 5765.394633563499, 5768.826935191951, 6291.150694656254, 6300.1494798202575, 6301.40136687014, 6305.0, 6322.178116844781, 5889.411058894162, 5984.0, 6087.9391206087375, 6102.389761023951, 6111.908321375183, 6132.846678833065, 6191.597546159485, 6199.876002479925, 6200.286966998763, 6209.19280493542, 6071.937410953065, 6121.510279177655, 6147.692615369214, 6152.723127459275, 6202.782902598387, 6073.301570319381, 6153.8307721532765, 6185.84535386619, 6199.318075011745, 6200.441960223601, 4871.439784424766, 4908.165611845995, 4919.065377578248, 4922.089413458521, 4929.285253638202, 4433.7561434121435, 4459.620932220732, 4466.1067786442845, 4475.060237350128, 4481.395011673448, 4736.976315118402, 5560.944390556043, 5643.830685079454, 5644.6613203207935, 5651.463111004448, 6107.419795119546, 6167.167432396569, 6172.722227499773, 6187.381261873777, 6192.535559833031, 5590.0, 5595.0, 5595.160725891067, 5605.579581531401, 5642.774289028475, 5167.896642067138, 5288.418273989858, 5294.311739473844, 5315.73421328932, 5315.973420132952, 6266.843328916813, 6284.340144284784, 6296.653684047421, 6307.590006649553, 6308.716107775161, 6172.475339596184, 6199.907001394892, 6207.6275423474735, 6217.378262173746, 6223.782167624111, 6193.690315484298, 6245.156903817927, 6303.968480157753, 6310.905336420049, 6321.589096708699, 6129.414292638856, 6136.3046386708, 6143.784967526115, 6144.508439324753, 6158.0147176451555, 6041.6375017499095, 6196.0705894116245, 6231.626102433868, 6236.03341482071, 6238.781642642486, 5824.271966004249, 5924.31870334917, 5953.1367951647635, 5970.731317090828, 6112.419320164755, 5927.31835838884, 5976.671283079472, 5990.550708696778, 5994.100884867305, 5996.160537524837, 5743.7989670363, 5884.029135192645, 5884.08796636522, 5904.173415721716, 5932.970335148297, 6026.939730602638, 6062.363451837494, 6066.211392518944, 6100.572959892765, 6108.961476548909, 5717.3996730342715, 5841.678707670949, 5864.9413505864395, 5878.794242201502, 5882.647041177543], "value": 5638.338750596148, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.775698692575, 526.8867193553386, 560.8138786466619, 585.2187329914564, 600.1747597054048, 28291.575626365597, 30219.0, 31249.843750781216, 37257.81371093144, 38215.0, 41352.58647413525, 42132.368014479805, 42153.15693686125, 42260.0, 42317.788411057976, 9413.246940244764, 9626.037396260388, 9634.71095867125, 9636.566354514029, 9642.71071867845, 8395.412321137523, 8492.023417306997, 11169.329840209573, 11398.316101033923, 11405.600803971862, 11417.48621312043, 11522.135839812006, 11544.653660390202, 11561.65315040546, 11636.243644163145, 8398.706045288385, 10971.615993440231, 11285.830712539318, 11429.971302582766, 11437.828432573519, 11268.154888383404, 11429.485673144645, 11432.485538150802, 11454.656360309204, 11459.25514841541, 30317.696823031707, 36798.264034719155, 37214.0, 37259.44110838312, 37323.25353492942, 2612.477504499089, 3307.669233076673, 3419.350323438539, 3516.507688923553, 4048.878533643966, 27396.315092122855, 27825.304367390978, 28521.714782852112, 28635.14094577166, 28680.856595716887, 2677.531431999412, 2778.2776478115634, 2799.2162194585344, 2828.123281782654, 2919.576661384109, 313222.0, 316209.0, 316291.0, 316514.4174279137, 316803.0, 57418.42581574214, 58736.412635873094, 60428.69785651044, 60454.69772651108, 60482.697586511786, 63507.0, 63760.36239637637, 63804.0, 63908.68045659835, 63924.68037659782, 15875.682486350208, 16417.425390111526, 16421.261043253082, 16441.424550140688, 16442.75335869963, 11205.663830084948, 11424.942875285737, 11443.427828608535, 12653.873461265453, 12709.618711438672, 15653.747700183958, 15885.602859928364, 17463.47609571715, 17956.55108622295, 17994.820051799576, 6603.240627327918, 7506.211847756016, 7683.385329173652, 7756.379489640815, 7888.1323054463965, 789.7907054630527, 936.2930987104693, 1000.3797645459862, 1060.3531845574194, 1096.6216655253925, 28259.71740282612, 28460.573091403647, 28918.42163156767, 29088.12735617935, 29121.271968200963, 1000.8848982366975, 1113.9610113645983, 1134.4838098665089, 1152.8731839497648, 1167.177140116216, 12559.434825432878, 14577.92711036438, 14680.66056506151, 14844.62888427798, 15592.376304947902, 458947.0, 460337.0, 464337.0, 465158.0, 466869.0, 46287.768561156976, 55200.72399637976, 56310.0, 56402.153967690516, 59452.0, 1260.684828792802, 1423.8220222472191, 1461.1087236785766, 1477.0546850015999, 1479.615300022002, 27947.441051178455, 28019.299517512227, 28516.42967140687, 28624.713752863037, 28732.13803585896, 49196.50803491919, 49261.75369123275, 49579.75210123926, 49621.0, 49755.75122124511, 417366.0, 419503.0, 420701.0, 420716.896415516, 421618.0, 25608.719564022096, 25804.838782255218, 25945.09192178265, 26272.474550509265, 26334.604980925684, 33712.831435842665, 34253.0, 37845.43231851469, 40940.59059409368, 44634.0, 29484.705152949053, 34840.82579587086, 34854.477182842784, 35532.0, 35848.64151358557, 35029.47455788216, 35266.82366588151, 35333.469997949534, 35837.64162358343, 35992.92021239367, 34570.48144277788, 34822.825885871425, 35356.823215883756, 35857.0, 36095.45856812097, 27542.724572754018, 31190.22024449407, 31728.04815855528, 31909.840450797597, 31914.20214494656, 23377.41556461102, 23647.763522364556, 24045.879770601034, 24139.275821725376, 24183.0], "value": 18907.27665043648, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.48137661728066, 402.46070265843764, 403.5319029925287, 410.56685197117065, 421.90718042030744, 14035.298235088256, 19445.0, 20210.6968395474, 20234.69647955282, 20243.392698219057, 15384.384624615002, 16183.352665893379, 18886.433406997778, 19312.71030934537, 19737.901310493427, 5693.715314234282, 5861.53107751381, 5915.526757859361, 5915.822525324247, 5917.5561832862495, 5461.2354270402175, 5704.8859022819515, 5806.912896306559, 5842.766289348442, 5848.0, 5798.159266906296, 5836.766529338822, 5838.065909454488, 5841.649501029951, 5850.122481627769, 5478.287822583058, 5732.31212254528, 5734.5699072569305, 5793.768249270024, 5817.418258174191, 5018.0, 5029.3964724232965, 5035.118854200537, 5043.470435604246, 5048.066107770074, 22635.434114147112, 24103.276901692978, 24530.14144504934, 24762.876185619138, 24777.0, 2087.332053742803, 2092.4245832396214, 2109.789021097893, 2159.276642324823, 2182.90176942038, 13175.736485270241, 14465.13209207451, 14555.636109097251, 14712.632184195374, 14909.925450372679, 1635.492997170881, 1688.6875927953245, 1702.4977631598708, 1733.9826601733948, 1767.2754170790042, 103671.48164259206, 106737.0, 107040.46479767551, 108063.91936080696, 108537.0, 11177.944110279397, 11243.044341230923, 11249.156313276537, 11320.6037788677, 11437.771244575062, 14397.92801036009, 14427.92786036063, 14476.27618619065, 14489.11641486621, 14518.782218266735, 7229.421646268391, 7350.485466017328, 8147.348212143014, 8261.669533218725, 8349.499030058216, 8046.074701409296, 8063.19368063189, 8066.838663226703, 8156.836863262702, 8192.0, 4102.856400025985, 4367.606915377632, 4482.708623939434, 4520.570545798144, 4531.0, 1819.1359104425558, 1822.4714832698369, 1915.946229573729, 1924.7978962209047, 1933.574613585009, 898.3217670658611, 901.8241442918568, 915.3866909170738, 932.7528205025674, 998.9960090109394, 18039.188236529386, 18677.346292879956, 18898.771579847267, 19197.616047678966, 19316.76156089433, 861.4314552395389, 964.4936408385654, 992.0525897767615, 1003.8895721470633, 1031.3966329697062, 8757.737267881974, 10114.645987390553, 10147.64483243083, 10149.797004059878, 10182.949085254526, 247530.48460396234, 589838.0, 590274.0, 616311.0, 633464.0, 104911.95088049174, 105538.0, 106477.0, 106741.0, 108077.0, 915.6703586708779, 952.6284748947916, 1012.119456073221, 1014.06705830636, 1034.3586976074732, 16747.832521675115, 16773.245203966097, 17056.720745943854, 17174.65650686954, 17486.650266994333, 37162.62837371592, 45957.0, 46475.0, 47337.0, 48288.0, 64880.35119648932, 65753.34246657472, 67583.66208168928, 68297.2925676862, 68684.0, 7059.2940705928995, 7682.769516914502, 7887.369010479262, 7905.169957154416, 7918.287354138155, 9892.604295828087, 10385.0, 11776.352300623548, 12045.277283363026, 12046.096542759153, 9984.500774961078, 10091.798164036825, 10177.338472999232, 10277.17782577407, 10439.634612788515, 10246.487675616338, 10265.538050787882, 10300.896991029993, 10423.68728938133, 10594.417307048187, 8905.376623636543, 10236.87394386602, 10470.581176752845, 10601.1519078472, 10683.51924163399, 7342.889856652261, 7717.1896950819355, 7824.530528168328, 8086.0, 8180.181981801772, 8800.823983520166, 9786.608535658494, 10323.32898361604, 10686.0916822071, 10719.0], "value": 9088.582217667943, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.7520683880332, 1617.9433719819806, 1621.1894052973514, 1888.886666799992, 2023.9494012649673, 7898.526088434691, 8596.65613375465, 8786.956065219681, 8816.735497935073, 8836.072212417692, 8423.663053477869, 8673.869891951626, 8676.73969780906, 8683.088275731052, 8700.43447175933, 2146.8067873891346, 2228.6768418579377, 2228.687983682286, 2389.4982053768686, 2430.1008626808016, 2210.18223257395, 2399.436132508859, 2486.27897909606, 2488.340589743719, 2493.4514406830467, 2022.0, 2398.3164798032562, 2426.1265944260053, 2437.317551085699, 2444.6577479152934, 2133.9146434142617, 2404.218628945591, 2414.0, 2417.1177520205174, 2420.9636855447093, 2362.0905951208906, 2441.7680320369536, 2446.1438496526252, 2447.4370894694143, 2449.265220433863, 8606.44058136219, 8677.609507572175, 8692.261157801595, 8704.825903481895, 8715.172058654418, 5181.533661970403, 5385.380681221632, 5416.783328666848, 5423.7796495788525, 5428.864278393072, 3360.6975372216375, 3488.7034602058857, 3579.0294055933514, 3602.7297952653657, 3620.0, 991.2763682511735, 999.6251405722854, 1001.664442411793, 1020.5356562763999, 1021.5607288865784, 11808.46861891217, 11818.409079545989, 11856.644300670994, 11862.288262704265, 11894.58368957091, 4776.617870570346, 4808.797800549863, 4911.9754401227765, 4912.9508704913205, 4933.555979961749, 10091.192704583616, 10094.747631309276, 10096.747581310527, 10135.949320253352, 10137.64518241858, 5391.649542779707, 5404.270423492778, 5416.647917885325, 5463.207834864002, 5469.124940009579, 4827.493113223131, 4891.437484689235, 4937.481564435682, 4957.157283261854, 4997.975010124999, 3899.025243689078, 3909.354956432214, 3953.288408086572, 3989.0625702959783, 3997.520297564311, 1335.1455068756004, 1340.3030424179371, 1343.1135450602555, 1350.162899002605, 1359.286374653295, 795.8368534450424, 826.338928856912, 844.3456321351002, 852.9616167272487, 934.4626839567276, 5639.718014099279, 5697.686627235542, 5718.599698021098, 5818.4763371296785, 5978.043513037893, 392.8487532300085, 461.87067621066154, 475.6123759136293, 482.27899290560833, 960.0015983377349, 3292.3250733599552, 3841.4429907663234, 3892.9416058759143, 3913.882583522499, 3921.8039098044983, 10412.791744165073, 10514.842277365846, 10775.94612026935, 10895.346279223271, 10907.400092994803, 10432.321899076536, 10736.248462607542, 10866.565337386415, 10871.021608055155, 10896.237263391646, 8020.879686804703, 8251.917480825236, 8259.297959673375, 8345.791355216046, 8382.87425688615, 5684.289463817023, 5774.82675519735, 5794.5364370851075, 5876.265466816648, 5899.46904778572, 9955.452450115314, 10131.848022279524, 10150.949245253727, 10222.693319200436, 10239.641612543524, 9987.151092156957, 10027.448490333101, 10183.949080254552, 10388.324758890649, 10446.947765261126, 7098.112735908012, 7131.215566287808, 7230.060092187982, 7236.095488063992, 7236.529625574321, 8194.54929978833, 8289.171082891662, 8309.875351869849, 8314.293285070837, 8341.124181960808, 6669.633170175687, 7214.314640109181, 7470.402367810453, 7558.58427786455, 7582.848343033219, 7396.519226250277, 7428.33145016951, 7498.887516687145, 7551.282628150317, 7564.243575642392, 6931.826704332432, 7062.505624606434, 7086.220515743161, 7113.288671133052, 7120.893186602309, 4806.206975848946, 4813.350197723263, 4875.219964805614, 4938.407391113089, 5063.974680126576, 4895.412550493964, 4946.35697359341, 5004.649674522749, 5012.899742005213, 5190.299309593157], "value": 4533.815972600843, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.8119009784227, 322.75470642311853, 330.07414203160135, 331.6236072058215, 337.50049926109375, 5362.487570641227, 5422.755975981076, 5424.972875135623, 5427.0773968425365, 5428.864278393042, 4430.756308403044, 4772.713637181771, 4932.778024988876, 5205.791768329263, 5381.569474442045, 3195.7603179761495, 3931.528216614003, 4032.3749818778138, 4035.4148648445957, 4059.776712280823, 3201.6798320167914, 4164.97917510412, 4168.416421700964, 4175.832966681329, 4208.169446290874, 3595.370810108234, 3879.243547508237, 3893.5911729268446, 4061.97969010156, 4158.0, 3941.487606611151, 3988.581198974095, 4122.505299364096, 4168.353905144692, 4169.228692691847, 3381.9684996076185, 3531.611522732497, 3544.468329750532, 3586.318599466093, 3622.3479773640734, 4500.909981800379, 5101.081805275049, 5162.303089082963, 5182.7408629568745, 5203.43896830953, 3972.185701931126, 4409.4488188976375, 4565.885852853672, 4730.5032971538185, 4753.881152971168, 4767.332573439721, 5198.766055527511, 5632.155176723524, 5640.5487560995025, 5698.715064246771, 3602.495650608917, 3994.1826468956556, 4007.3788562772816, 4026.396040593905, 4054.412110243998, 4667.883302917387, 5048.823291184827, 5236.764345604457, 5974.193483879664, 6008.879822403571, 3644.2347107107803, 3983.820728067244, 4092.0767239397437, 4097.221527909687, 4133.834646614162, 3958.8218530166214, 4141.213169497786, 4145.97927010369, 4175.937360939588, 4175.958240417618, 3970.79620810453, 4800.447948485899, 4809.374781278481, 4820.156472617278, 4823.614110871192, 4496.662750293741, 4779.016708066183, 5426.945730542723, 5767.221425107557, 5770.394108618619, 1340.7653660609353, 1500.8499150085122, 1511.7581187010026, 1516.9696606067816, 1523.803814006022, 940.8823897012874, 985.4875464758286, 1020.2399212586671, 1027.2039169643585, 1028.3572767020612, 5442.727863606804, 5789.2763404574425, 5862.0, 5887.8233652990475, 5902.970485147547, 5378.273933019071, 6346.873062538723, 6403.551751377358, 6433.356664333529, 6440.098386225932, 4589.632829373642, 5224.712640804716, 5578.30271216098, 5800.4489573490455, 5846.766129354778, 3372.8313584321177, 3380.560527131506, 3400.778949368283, 3403.982980085084, 3407.3866703993517, 5924.940750592439, 6245.375462453809, 6707.698153583003, 6727.275453441364, 6736.629485378351, 6099.68212079407, 6134.77304539094, 6139.662318572432, 6149.2313460817395, 6192.195014648067, 5368.489993450616, 5524.806631767869, 5918.704064796829, 6104.6337219766065, 6330.974088291702, 5249.580033597379, 5631.324241091095, 5669.281930781706, 5946.818452128091, 6013.879722405615, 5557.693941923564, 5741.6172288501475, 5894.351621321565, 5898.882022359443, 5900.0, 5933.554983376178, 6158.938410615837, 6184.783532576518, 6205.65868877198, 6212.751489940351, 3158.289384888382, 3614.8554057838446, 3953.723239373216, 3969.980150099231, 4007.499062617173, 5176.7152806594495, 5978.760849565969, 5983.491403230774, 5998.310194327708, 6004.939950600438, 5126.871828204325, 5484.095124304445, 5738.368779434342, 5800.883982320245, 5842.370933080216, 5000.374953130859, 5506.2291279221745, 5621.7189140542, 5820.417958204146, 5827.5629327801435, 4700.529947005272, 5598.552115830642, 5608.989572455714, 5612.691301978431, 5620.213170156099, 4984.900301994012, 5107.32755567106, 5461.808836690697, 5489.39616642177, 5701.859628074319, 4562.680612357103, 4801.4718380977365, 4955.554000140005, 5644.209810626434, 5667.3482549507335], "value": 4233.8287979585475, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.9852602947941, 752.059925093633, 755.7392699518667, 767.842592268585, 791.5923299500756, 14500.057496262736, 14929.552113436614, 14996.700065998688, 15024.0, 16232.269547870344, 15822.604434889134, 15952.920235398837, 15955.680886382266, 15959.281832317545, 15967.680646387065, 734.9522281051741, 740.2153717059933, 934.7055677461578, 965.1072757699135, 965.835807912653, 685.5715178013742, 943.2406912435487, 944.0182210501071, 949.2263804998938, 949.9050094990515, 982.882054153499, 988.9851652225223, 990.8315586350337, 991.0386924683082, 991.6479649724353, 773.3928865840288, 775.9573223472706, 785.3873978296938, 980.294188184506, 984.4634674102631, 594.0406243916087, 726.1467775363978, 748.3451979517922, 750.3734381791211, 751.751921865783, 14996.47512337074, 15094.018888772305, 15096.54710358691, 15123.546293611209, 15147.0, 10229.846552301722, 12022.0, 12158.209716368408, 12518.74962500745, 12522.060845436628, 8549.572521373906, 9539.046095390475, 10100.646477373257, 10107.191424686007, 10362.896371036268, 3404.0, 3410.38613049651, 3428.708559772422, 3451.3097380523996, 3530.3998320285614, 76427.61786191033, 76451.0, 76767.61616191939, 78523.60738196272, 79052.60473697653, 8274.524699626172, 8930.374873758905, 8976.0, 9198.172164505228, 9198.862017069749, 20680.793192068188, 21795.128194872028, 21908.0, 21949.341519754435, 21978.45053873666, 7292.927070729331, 7408.777736667908, 7657.34912532441, 7714.614269286513, 7801.570913599693, 3708.42519409492, 3727.9068023299633, 3755.7746535207975, 3885.8277488210474, 3917.216556688674, 3481.060113769268, 3548.3435564420665, 3549.290141971616, 3551.8224088795455, 3557.9822100889846, 629.7827249598881, 643.0547095769211, 643.46358174867, 646.2826262848234, 650.5543702563759, 511.23825500005466, 533.6531254684486, 609.3723464831231, 613.9263288405419, 620.6276234259499, 6870.896936545956, 7683.8474228865, 8024.398170137263, 8152.225538573826, 8294.58527073644, 577.3389469057905, 590.8965930962064, 595.3540408656656, 603.5292471871946, 613.9341117140879, 3640.4357324614725, 3741.551013878352, 3782.0, 3786.1291902861944, 3825.3114439401174, 88921.0, 89070.0, 89569.0, 91048.54475727578, 91775.5411222953, 75114.24885751073, 75555.62222188964, 76927.61536192395, 77089.0, 77429.83855242176, 54727.72636136953, 63621.68189159025, 64214.678926606, 64413.67793161098, 65761.0135847953, 7138.964305178649, 7703.343781087093, 8042.356611470951, 8275.089740128702, 8320.41757077023, 38100.856974290815, 39566.604333957446, 41107.0, 41141.1771764569, 42715.718528444195, 54470.91058178735, 54473.0, 54890.451095490134, 55111.72444137753, 55892.0, 10685.57257709683, 10735.838962415726, 10738.516766745672, 10939.343639381663, 11206.775864482828, 11745.588904388305, 11876.406179691154, 11878.58424955157, 12302.261864288172, 12401.069919756235, 9502.524873756147, 10993.61522346714, 11233.269837460539, 11953.462094205608, 12299.815502767644, 11123.944380278046, 11217.943910280397, 11295.3787541686, 11439.313641181556, 11471.483783229942, 10928.50821713009, 11096.167787415814, 11195.160362972647, 11398.15888915991, 11451.25566838153, 9389.154976052188, 9570.33007689455, 9652.372595781251, 9852.901470985486, 9967.601295948081, 9452.054794520493, 9489.383190092622, 9512.952435238056, 9565.091316324939, 9595.808083838423], "value": 6738.560618124404, "unit": "score"}], "name": "dromaeo_css", "value": 7074.251147062418, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 112.0, 108.0, 111.0, 113.0, 112.0, 112.0, 117.0, 152.0, 155.0], "value": 122.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 134.0, 175.0, 208.0, 119.0, 144.0, 143.0, 122.0, 167.0, 114.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 189.0, 186.0, 182.0, 186.0, 184.0, 209.0, 209.0, 183.0, 185.0], "value": 190.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 79.0, 150.0, 56.0, 55.0, 65.0, 68.0, 128.0, 57.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 143.0, 80.0, 79.0, 84.0, 130.0, 80.0, 78.0, 135.0], "value": 98.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 82.0, 85.0, 83.0, 86.0, 82.0, 86.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 92.0, 93.0, 91.0, 110.0, 148.0, 108.0, 91.0, 92.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 85.0, 72.0, 126.0, 138.0, 86.0, 85.0, 84.0, 84.0, 87.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 58.0, 58.0, 58.0, 61.0, 92.0, 89.0, 56.0], "value": 65.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 67.0, 80.0, 74.0, 72.0, 67.0, 68.0, 68.0, 70.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 82.0, 82.0, 82.0, 82.0, 81.0, 83.0, 83.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 157.0, 138.0, 137.0, 139.0, 139.0, 141.0, 140.0, 183.0, 135.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 200.0, 222.0, 281.0, 237.0, 198.0, 204.0, 199.0, 194.0, 198.0], "value": 213.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 72.0, 70.0, 71.0, 72.0, 69.0, 72.0, 80.0, 102.0], "value": 74.7, "unit": "score"}], "name": "kraken", "value": 1570.4, "alertThreshold": 2.0}]} 05:31:43 INFO - Return code: 0 05:31:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:31:43 ERROR - # TBPL SUCCESS # 05:31:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:31:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:31:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:31:43 INFO - 2016-04-27 05:31:43.263 screenresolution[1946:16304] starting screenresolution argv=screenresolution get 05:31:43 INFO - 2016-04-27 05:31:43.266 screenresolution[1946:16304] Display 0: 1600x1200x32@60 05:31:43 INFO - 2016-04-27 05:31:43.272 screenresolution[1947:16307] starting screenresolution argv=screenresolution list 05:31:43 INFO - Available Modes on Display 0 05:31:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:31:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:31:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:31:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:31:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:31:43 INFO - Intel Iris: 05:31:43 INFO - Chipset Model: Intel Iris 05:31:43 INFO - Type: GPU 05:31:43 INFO - Bus: Built-In 05:31:43 INFO - VRAM (Dynamic, Max): 1536 MB 05:31:43 INFO - Vendor: Intel (0x8086) 05:31:43 INFO - Device ID: 0x0a2e 05:31:43 INFO - Revision ID: 0x0009 05:31:43 INFO - Displays: 05:31:43 INFO - Display: 05:31:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:31:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:31:43 INFO - Display Serial Number: 1600x1200 60 05:31:43 INFO - Main Display: Yes 05:31:43 INFO - Mirror: Off 05:31:43 INFO - Online: Yes 05:31:43 INFO - Rotation: Supported 05:31:43 INFO - Return code: 0 05:31:43 INFO - Running post-action listener: _resource_record_post_action 05:31:43 INFO - Running post-run listener: _resource_record_post_run 05:31:44 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 811008; Write bytes: 694800384; Read time: 13; Write time: 2072 05:31:44 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380527104; Write bytes: 374576640; Read time: 28307; Write time: 700 05:31:44 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 811008; Write bytes: 297064960; Read time: 13; Write time: 1324 05:31:44 INFO - Running post-run listener: _upload_blobber_files 05:31:44 INFO - Blob upload gear active. 05:31:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:31:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:31:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:31:44 INFO - (blobuploader) - INFO - Open directory for files ... 05:31:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:31:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:31:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:46 INFO - (blobuploader) - INFO - Done attempting. 05:31:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:31:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:31:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:47 INFO - (blobuploader) - INFO - Done attempting. 05:31:47 INFO - (blobuploader) - INFO - Iteration through files over. 05:31:47 INFO - Return code: 0 05:31:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:31:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:31:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f26a362672bac6d5d607b019c91454faf169eecb834111e9ac3d986db7de6cb22d8e47b3b73f280faaeae910445a2c0f6d93df326683f6e2d40898a3f91530", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/004c0499b2f0e93fb48afc662d3bfd654a0b9e046fdcc346f65225ea3e550644a8b0bef76d5401e49d74cb48ca4bbc635b69b103f4586fd1be3a1bf332ea9e2a"} 05:31:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:31:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:31:47 INFO - Contents: 05:31:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f26a362672bac6d5d607b019c91454faf169eecb834111e9ac3d986db7de6cb22d8e47b3b73f280faaeae910445a2c0f6d93df326683f6e2d40898a3f91530", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/004c0499b2f0e93fb48afc662d3bfd654a0b9e046fdcc346f65225ea3e550644a8b0bef76d5401e49d74cb48ca4bbc635b69b103f4586fd1be3a1bf332ea9e2a"} 05:31:47 INFO - Running post-run listener: copy_logs_to_upload_dir 05:31:47 INFO - Copying logs to upload dir... 05:31:47 INFO - mkdir: /builds/slave/test/build/upload/logs 05:31:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.303534 ========= master_lag: 2.24 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-27 05:31:49.280941) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 05:31:49.285391) ========= 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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f26a362672bac6d5d607b019c91454faf169eecb834111e9ac3d986db7de6cb22d8e47b3b73f280faaeae910445a2c0f6d93df326683f6e2d40898a3f91530", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/004c0499b2f0e93fb48afc662d3bfd654a0b9e046fdcc346f65225ea3e550644a8b0bef76d5401e49d74cb48ca4bbc635b69b103f4586fd1be3a1bf332ea9e2a"} build_url:https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011155 build_url: 'https://queue.taskcluster.net/v1/task/GktKpQirTjuQ-5fVSbTY8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7f26a362672bac6d5d607b019c91454faf169eecb834111e9ac3d986db7de6cb22d8e47b3b73f280faaeae910445a2c0f6d93df326683f6e2d40898a3f91530", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/004c0499b2f0e93fb48afc662d3bfd654a0b9e046fdcc346f65225ea3e550644a8b0bef76d5401e49d74cb48ca4bbc635b69b103f4586fd1be3a1bf332ea9e2a"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 05:31:49.332971) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-27 05:31:49.333331) ========= 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.YSJdxw3Q2i/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.aL2HsM1FIi/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.004663 ========= master_lag: 2.72 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-27 05:31:52.054943) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 05:31:52.059316) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 05:31:52.080381) ========= ========= Total master_lag: 5.07 =========