builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0159 starttime: 1460808920.77 results: success (0) buildid: 20160416030220 builduid: a806c9e3234c4fd7a14e2dc1fc6463d2 revision: a5322c766f510d2a6544b519ffcc72a19879c779 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:20.773991) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:20.774419) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:20.774714) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:20.794214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:20.794625) ========= 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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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-16 05:15:20-- 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% 41.1M=0s 2016-04-16 05:15:21 (41.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.715285 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:21.540202) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:21.540666) ========= 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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.026817 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:21.598251) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:21.598558) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a5322c766f510d2a6544b519ffcc72a19879c779 --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 a5322c766f510d2a6544b519ffcc72a19879c779 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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-16 05:15:21,674 truncating revision to first 12 chars 2016-04-16 05:15:21,674 Setting DEBUG logging. 2016-04-16 05:15:21,674 attempt 1/10 2016-04-16 05:15:21,674 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a5322c766f51?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-16 05:15:22,279 unpacking tar archive at: mozilla-central-a5322c766f51/testing/mozharness/ program finished with exit code 0 elapsedTime=0.853630 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:22.469829) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:22.470213) ========= script_repo_revision: a5322c766f510d2a6544b519ffcc72a19879c779 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:22.470828) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:22.471536) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-16 05:15:22.485191) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 29 secs) (at 2016-04-16 05:15:22.485480) ========= /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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:15:22 INFO - MultiFileLogger online at 20160416 05:15:22 in /builds/slave/test 05:15:22 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:15:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:15:22 INFO - {'append_to_log': False, 05:15:22 INFO - 'base_work_dir': '/builds/slave/test', 05:15:22 INFO - 'blob_upload_branch': 'Firefox', 05:15:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:15:22 INFO - 'branch': 'Firefox', 05:15:22 INFO - 'buildbot_json_path': 'buildprops.json', 05:15:22 INFO - 'config_files': ('talos/mac_config.py',), 05:15:22 INFO - 'default_actions': ('clobber', 05:15:22 INFO - 'read-buildbot-config', 05:15:22 INFO - 'download-and-extract', 05:15:22 INFO - 'populate-webroot', 05:15:22 INFO - 'create-virtualenv', 05:15:22 INFO - 'install', 05:15:22 INFO - 'run-tests'), 05:15:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:15:22 INFO - 'download_minidump_stackwalk': True, 05:15:22 INFO - 'download_symbols': 'ondemand', 05:15:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:15:22 INFO - 'tooltool.py': '/tools/tooltool.py', 05:15:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:15:22 INFO - '/tools/misc-python/virtualenv.py')}, 05:15:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:15:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:15:22 INFO - 'installer_path': 'installer.exe', 05:15:22 INFO - 'log_level': 'info', 05:15:22 INFO - 'log_name': 'talos', 05:15:22 INFO - 'log_to_console': True, 05:15:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:15:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:15:22 INFO - 'opt_config_files': (), 05:15:22 INFO - 'pip_index': False, 05:15:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:15:22 INFO - 'cmd': ('bash', 05:15:22 INFO - '-c', 05:15:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:15:22 INFO - 'enabled': True, 05:15:22 INFO - 'halt_on_failure': False, 05:15:22 INFO - 'name': 'check_screen_resolution'},), 05:15:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:15:22 INFO - 'cmd': ('bash', 05:15:22 INFO - '-c', 05:15:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:15:22 INFO - 'enabled': True, 05:15:22 INFO - 'halt_on_failure': False, 05:15:22 INFO - 'name': 'check_screen_resolution'},), 05:15:22 INFO - 'run_cmd_checks_enabled': True, 05:15:22 INFO - 'sps_profile': False, 05:15:22 INFO - 'sps_profile_interval': 0, 05:15:22 INFO - 'suite': 'dromaeojs', 05:15:22 INFO - 'system_bits': '32', 05:15:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:15:22 INFO - 'title': 't-yosemite-r7-0159', 05:15:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:15:22 INFO - 'use_talos_json': True, 05:15:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:15:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:15:22 INFO - 'work_dir': 'build'} 05:15:22 INFO - ##### 05:15:22 INFO - ##### Running clobber step. 05:15:22 INFO - ##### 05:15:22 INFO - Running pre-action listener: _resource_record_pre_action 05:15:22 INFO - Running main action method: clobber 05:15:22 INFO - rmtree: /builds/slave/test/build 05:15:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:15:24 INFO - Running post-action listener: _resource_record_post_action 05:15:24 INFO - ##### 05:15:24 INFO - ##### Running read-buildbot-config step. 05:15:24 INFO - ##### 05:15:24 INFO - Running pre-action listener: _resource_record_pre_action 05:15:24 INFO - Running main action method: read_buildbot_config 05:15:24 INFO - Using buildbot properties: 05:15:24 INFO - { 05:15:24 INFO - "project": "", 05:15:24 INFO - "product": "firefox", 05:15:24 INFO - "script_repo_revision": "production", 05:15:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:15:24 INFO - "repository": "", 05:15:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:15:24 INFO - "buildid": "20160416030220", 05:15:24 INFO - "stage_platform": "macosx64", 05:15:24 INFO - "basedir": "/builds/slave/test", 05:15:24 INFO - "nightly_build": "True", 05:15:24 INFO - "buildnumber": 223, 05:15:24 INFO - "slavename": "t-yosemite-r7-0159", 05:15:24 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:15:24 INFO - "platform": "yosemite_r7", 05:15:24 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:15:24 INFO - "branch": "mozilla-central", 05:15:24 INFO - "repo_path": "mozilla-central", 05:15:24 INFO - "revision": "a5322c766f510d2a6544b519ffcc72a19879c779", 05:15:24 INFO - "pgo_build": "False", 05:15:24 INFO - "builduid": "a806c9e3234c4fd7a14e2dc1fc6463d2", 05:15:24 INFO - "slavebuilddir": "test" 05:15:24 INFO - } 05:15:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:15:24 INFO - Running post-action listener: _resource_record_post_action 05:15:24 INFO - ##### 05:15:24 INFO - ##### Running download-and-extract step. 05:15:24 INFO - ##### 05:15:24 INFO - Running pre-action listener: _resource_record_pre_action 05:15:24 INFO - Running main action method: download_and_extract 05:15:24 INFO - mkdir: /builds/slave/test/build/tests 05:15:24 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:15:24 INFO - https://queue.taskcluster.net/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:15:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:15:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:15:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:15:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:15:55 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json: timed out 05:15:55 INFO - retry: attempt #1 caught exception: timed out 05:15:55 INFO - retry: Failed, sleeping 30 seconds before retrying 05:16:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #2 05:16:39 INFO - Downloaded 1199 bytes. 05:16:39 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:16:39 INFO - Using the following test package requirements: 05:16:39 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:16:39 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:16:39 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:16:39 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:16:39 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:16:39 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:16:39 INFO - u'jsshell-mac.zip'], 05:16:39 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:16:39 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:16:39 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:16:39 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:16:39 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:16:39 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:16:39 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:16:39 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:16:39 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:16:39 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:16:39 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:16:39 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:39 INFO - https://queue.taskcluster.net/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:16:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:16:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:16:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:16:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:16:41 INFO - Downloaded 18032120 bytes. 05:16:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:16:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:16:44 INFO - Return code: 0 05:16:44 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:16:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:44 INFO - https://queue.taskcluster.net/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:16:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:16:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:16:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:16:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:16:50 INFO - Downloaded 18032120 bytes. 05:16:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:16:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:16:54 INFO - Return code: 0 05:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:54 INFO - https://queue.taskcluster.net/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:16:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:16:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:16:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:16:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:16:55 INFO - Downloaded 10841076 bytes. 05:16:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:16:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:16:55 INFO - Return code: 0 05:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:55 INFO - https://queue.taskcluster.net/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:16:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:16:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:16:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:16:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:17:01 INFO - Downloaded 109986833 bytes. 05:17:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:17:01 INFO - mkdir: /builds/slave/test/properties 05:17:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:17:01 INFO - Writing to file /builds/slave/test/properties/build_url 05:17:01 INFO - Contents: 05:17:01 INFO - build_url:https://queue.taskcluster.net/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:17:02 INFO - Running post-action listener: _resource_record_post_action 05:17:02 INFO - Running post-action listener: set_extra_try_arguments 05:17:02 INFO - ##### 05:17:02 INFO - ##### Running populate-webroot step. 05:17:02 INFO - ##### 05:17:02 INFO - Running pre-action listener: _resource_record_pre_action 05:17:02 INFO - Running main action method: populate_webroot 05:17:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:17:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:17:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:17:02 INFO - '--tppagecycles', 05:17:02 INFO - '7'], 05:17:02 INFO - 'tests': ['tsvgm']}}, 05:17:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:17:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:17:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:17:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:17:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:17:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:17:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:02 INFO - 'talos_options': ['--disable-e10s'], 05:17:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:02 INFO - 'talos_options': ['--disable-e10s'], 05:17:02 INFO - 'tests': ['damp', 'tps']}, 05:17:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:02 INFO - 'tests': ['damp', 'tps']}, 05:17:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:17:02 INFO - 'tests': ['dromaeo_dom']}, 05:17:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:17:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:17:02 INFO - 'tests': ['a11yr', 05:17:02 INFO - 'ts_paint', 05:17:02 INFO - 'tpaint', 05:17:02 INFO - 'sessionrestore', 05:17:02 INFO - 'sessionrestore_no_auto_restore', 05:17:02 INFO - 'tabpaint']}, 05:17:02 INFO - 'other-e10s': {'tests': ['a11yr', 05:17:02 INFO - 'ts_paint', 05:17:02 INFO - 'tpaint', 05:17:02 INFO - 'sessionrestore', 05:17:02 INFO - 'sessionrestore_no_auto_restore', 05:17:02 INFO - 'tabpaint']}, 05:17:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:17:02 INFO - 'ts_paint', 05:17:02 INFO - 'tpaint', 05:17:02 INFO - 'sessionrestore', 05:17:02 INFO - 'sessionrestore_no_auto_restore', 05:17:02 INFO - 'tabpaint']}, 05:17:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:17:02 INFO - 'ts_paint', 05:17:02 INFO - 'tpaint', 05:17:02 INFO - 'sessionrestore', 05:17:02 INFO - 'sessionrestore_no_auto_restore', 05:17:02 INFO - 'tabpaint']}, 05:17:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:17:02 INFO - 'tests': ['a11yr', 05:17:02 INFO - 'ts_paint', 05:17:02 INFO - 'tpaint', 05:17:02 INFO - 'sessionrestore', 05:17:02 INFO - 'sessionrestore_no_auto_restore', 05:17:02 INFO - 'tabpaint']}, 05:17:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:17:02 INFO - 'tests': ['a11yr', 05:17:02 INFO - 'ts_paint', 05:17:02 INFO - 'tpaint', 05:17:02 INFO - 'sessionrestore', 05:17:02 INFO - 'sessionrestore_no_auto_restore', 05:17:02 INFO - 'tabpaint']}, 05:17:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:17:02 INFO - 'tests': ['tsvgx', 05:17:02 INFO - 'tsvgr_opacity', 05:17:02 INFO - 'tart', 05:17:02 INFO - 'tscrollx', 05:17:02 INFO - 'cart']}, 05:17:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:17:02 INFO - 'tsvgr_opacity', 05:17:02 INFO - 'tart', 05:17:02 INFO - 'tscrollx', 05:17:02 INFO - 'cart']}, 05:17:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:02 INFO - 'talos_options': ['--disable-e10s'], 05:17:02 INFO - 'tests': ['tp5o']}, 05:17:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:02 INFO - 'tests': ['tp5o']}, 05:17:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:02 INFO - 'talos_options': ['--disable-e10s', 05:17:02 INFO - '--xperf_path', 05:17:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:02 INFO - 'tests': ['tp5n']}, 05:17:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:02 INFO - 'talos_options': ['--xperf_path', 05:17:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:02 INFO - 'tests': ['tp5n']}}, 05:17:02 INFO - 'talos.zip': {'path': '', 05:17:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:17:02 INFO - Running post-action listener: _resource_record_post_action 05:17:02 INFO - ##### 05:17:02 INFO - ##### Running create-virtualenv step. 05:17:02 INFO - ##### 05:17:02 INFO - Running pre-action listener: _resource_record_pre_action 05:17:02 INFO - Running main action method: create_virtualenv 05:17:02 INFO - Creating virtualenv /builds/slave/test/build/venv 05:17:02 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:17:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:17:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:17:03 INFO - Using real prefix '/tools/python27' 05:17:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:17:03 INFO - Installing distribute.............................................................................................................................................................................................done. 05:17:06 INFO - Installing pip.................done. 05:17:06 INFO - Return code: 0 05:17:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:17:06 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:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:06 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:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:06 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 0x10c638a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c648420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8259d72c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d7e00>, '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 0x10c300db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d2b20>, '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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:17:06 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:17:06 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:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IlOL4jQ2E/Render', 05:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:06 INFO - 'HOME': '/Users/cltbld', 05:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:06 INFO - 'LOGNAME': 'cltbld', 05:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:06 INFO - 'MOZ_NO_REMOTE': '1', 05:17:06 INFO - 'NO_EM_RESTART': '1', 05:17:06 INFO - 'PAGER': '/bin/cat', 05:17:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:06 INFO - 'PWD': '/builds/slave/test', 05:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:06 INFO - 'SHELL': '/bin/bash', 05:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOjbPiaZjN/Listeners', 05:17:06 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:06 INFO - 'USER': 'cltbld', 05:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:06 INFO - 'XPC_FLAGS': '0x0', 05:17:06 INFO - 'XPC_SERVICE_NAME': '0', 05:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:06 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:17:06 INFO - Cleaning up... 05:17:06 INFO - Return code: 0 05:17:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:17:06 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:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:06 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:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:06 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 0x10c638a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c648420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8259d72c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d7e00>, '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 0x10c300db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d2b20>, '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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:17:06 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:17:06 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:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IlOL4jQ2E/Render', 05:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:06 INFO - 'HOME': '/Users/cltbld', 05:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:06 INFO - 'LOGNAME': 'cltbld', 05:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:06 INFO - 'MOZ_NO_REMOTE': '1', 05:17:06 INFO - 'NO_EM_RESTART': '1', 05:17:06 INFO - 'PAGER': '/bin/cat', 05:17:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:06 INFO - 'PWD': '/builds/slave/test', 05:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:06 INFO - 'SHELL': '/bin/bash', 05:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOjbPiaZjN/Listeners', 05:17:06 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:06 INFO - 'USER': 'cltbld', 05:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:06 INFO - 'XPC_FLAGS': '0x0', 05:17:06 INFO - 'XPC_SERVICE_NAME': '0', 05:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:07 INFO - Downloading/unpacking psutil>=0.7.1 05:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:17:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:09 INFO - Installing collected packages: psutil 05:17:09 INFO - Running setup.py install for psutil 05:17:09 INFO - building 'psutil._psutil_osx' extension 05:17:09 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:17:10 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:17:10 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:17:10 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:17:10 INFO - building 'psutil._psutil_posix' extension 05:17:10 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:17:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:17:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:17:10 INFO - ^ 05:17:10 INFO - 1 warning generated. 05:17:10 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:17:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:10 INFO - Successfully installed psutil 05:17:10 INFO - Cleaning up... 05:17:10 INFO - Return code: 0 05:17:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:11 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 0x10c638a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c648420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8259d72c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d7e00>, '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 0x10c300db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d2b20>, '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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:17:11 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:17:11 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:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IlOL4jQ2E/Render', 05:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:11 INFO - 'HOME': '/Users/cltbld', 05:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:11 INFO - 'LOGNAME': 'cltbld', 05:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:11 INFO - 'MOZ_NO_REMOTE': '1', 05:17:11 INFO - 'NO_EM_RESTART': '1', 05:17:11 INFO - 'PAGER': '/bin/cat', 05:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:11 INFO - 'PWD': '/builds/slave/test', 05:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:11 INFO - 'SHELL': '/bin/bash', 05:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOjbPiaZjN/Listeners', 05:17:11 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:11 INFO - 'USER': 'cltbld', 05:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:11 INFO - 'XPC_FLAGS': '0x0', 05:17:11 INFO - 'XPC_SERVICE_NAME': '0', 05:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:17: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:17: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:17: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:17: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:17: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:17: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:17:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:17:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:17:13 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:17:13 INFO - Installing collected packages: mozsystemmonitor 05:17:13 INFO - Running setup.py install for mozsystemmonitor 05:17:13 INFO - Successfully installed mozsystemmonitor 05:17:13 INFO - Cleaning up... 05:17:13 INFO - Return code: 0 05:17:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:17: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:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:13 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 0x10c638a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c648420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8259d72c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d7e00>, '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 0x10c300db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d2b20>, '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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:17:13 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:17:13 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:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IlOL4jQ2E/Render', 05:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:13 INFO - 'HOME': '/Users/cltbld', 05:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:13 INFO - 'LOGNAME': 'cltbld', 05:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:13 INFO - 'MOZ_NO_REMOTE': '1', 05:17:13 INFO - 'NO_EM_RESTART': '1', 05:17:13 INFO - 'PAGER': '/bin/cat', 05:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:13 INFO - 'PWD': '/builds/slave/test', 05:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:13 INFO - 'SHELL': '/bin/bash', 05:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOjbPiaZjN/Listeners', 05:17:13 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:13 INFO - 'USER': 'cltbld', 05:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:13 INFO - 'XPC_FLAGS': '0x0', 05:17:13 INFO - 'XPC_SERVICE_NAME': '0', 05:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:14 INFO - Downloading/unpacking blobuploader==1.2.4 05:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:16 INFO - Downloading blobuploader-1.2.4.tar.gz 05:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:17:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:17:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:17:17 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:17:17 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:17:17 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:17:17 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:17:17 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:17:17 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:17:17 INFO - Downloading docopt-0.6.1.tar.gz 05:17:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:17:17 INFO - Installing collected packages: blobuploader, requests, docopt 05:17:17 INFO - Running setup.py install for blobuploader 05:17:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:17:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:17:18 INFO - Running setup.py install for requests 05:17:18 INFO - Running setup.py install for docopt 05:17:18 INFO - Successfully installed blobuploader requests docopt 05:17:18 INFO - Cleaning up... 05:17:18 INFO - Return code: 0 05:17:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17: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:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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:17:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:18 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 0x10c638a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c648420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8259d72c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d7e00>, '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 0x10c300db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d2b20>, '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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:17:18 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:17:18 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:17:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IlOL4jQ2E/Render', 05:17:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:18 INFO - 'HOME': '/Users/cltbld', 05:17:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:18 INFO - 'LOGNAME': 'cltbld', 05:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:18 INFO - 'MOZ_NO_REMOTE': '1', 05:17:18 INFO - 'NO_EM_RESTART': '1', 05:17:18 INFO - 'PAGER': '/bin/cat', 05:17:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:18 INFO - 'PWD': '/builds/slave/test', 05:17:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:18 INFO - 'SHELL': '/bin/bash', 05:17:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOjbPiaZjN/Listeners', 05:17:18 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:17:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:18 INFO - 'USER': 'cltbld', 05:17:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:18 INFO - 'XPC_FLAGS': '0x0', 05:17:18 INFO - 'XPC_SERVICE_NAME': '0', 05:17:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-mAIozH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-5p6YwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-2r7ryV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Viog0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-yPl4Tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-r27b7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-m2QnII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-jUHW0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-4VU7b2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:19 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-hFxKhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-4JbhHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-1smFBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-d8DiPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kSCN9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Bi4vkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-GcwmkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:20 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-qg3G7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:17:20 INFO - Running setup.py install for manifestparser 05:17:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:17:20 INFO - Running setup.py install for mozcrash 05:17:21 INFO - Running setup.py install for mozdebug 05:17:21 INFO - Running setup.py install for mozdevice 05:17:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:17:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:17:21 INFO - Running setup.py install for mozfile 05:17:21 INFO - Running setup.py install for mozhttpd 05:17:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:17:21 INFO - Running setup.py install for mozinfo 05:17:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:17:21 INFO - Running setup.py install for mozInstall 05:17:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:17:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:17:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:17:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:17:21 INFO - Running setup.py install for mozleak 05:17:22 INFO - Running setup.py install for mozlog 05:17:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:17:22 INFO - Running setup.py install for moznetwork 05:17:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:17:22 INFO - Running setup.py install for mozprocess 05:17:22 INFO - Running setup.py install for mozprofile 05:17:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:17:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:17:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:17:22 INFO - Running setup.py install for mozrunner 05:17:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:17:22 INFO - Running setup.py install for mozscreenshot 05:17:22 INFO - Running setup.py install for moztest 05:17:23 INFO - Running setup.py install for mozversion 05:17:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:17:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:17:23 INFO - Cleaning up... 05:17:23 INFO - Return code: 0 05:17:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17: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:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17: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:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:23 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 0x10c638a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c648420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8259d72c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d7e00>, '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 0x10c300db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d2b20>, '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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:17:23 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:17:23 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:17:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IlOL4jQ2E/Render', 05:17:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:23 INFO - 'HOME': '/Users/cltbld', 05:17:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:23 INFO - 'LOGNAME': 'cltbld', 05:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:23 INFO - 'MOZ_NO_REMOTE': '1', 05:17:23 INFO - 'NO_EM_RESTART': '1', 05:17:23 INFO - 'PAGER': '/bin/cat', 05:17:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:23 INFO - 'PWD': '/builds/slave/test', 05:17:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:23 INFO - 'SHELL': '/bin/bash', 05:17:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOjbPiaZjN/Listeners', 05:17:23 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:17:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:23 INFO - 'USER': 'cltbld', 05:17:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:23 INFO - 'XPC_FLAGS': '0x0', 05:17:23 INFO - 'XPC_SERVICE_NAME': '0', 05:17:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:17:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-8NwU_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:17:23 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:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:17:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Zd7u3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:17:23 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:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:17:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-mIFgia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:17:23 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:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:17:23 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-PI8_t0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:17:24 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:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:17:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-1DLF_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:17:24 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:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:17:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-pgrPAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:17:24 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:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:17:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-2vrJho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:17:24 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:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:17:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-UrYrAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:17:24 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:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:17:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-lrrccD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:17:24 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:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:17:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-lzt2mm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:17:24 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:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:17:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-jjQ1Fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:17:24 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:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:17:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-2aM0UX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:17:24 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:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:17:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-pivSMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:17:24 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:17:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:17:24 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-nug5Y_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:17:25 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:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:25 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-rzAByo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:17:25 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:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:17:25 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kNXeKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:17:25 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:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:17:25 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-SiORPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:17:25 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:17:25 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:17:25 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:17:25 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:17:25 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:17:25 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:17:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:27 INFO - Downloading blessings-1.6.tar.gz 05:17:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:17:27 INFO - Installing collected packages: blessings 05:17:27 INFO - Running setup.py install for blessings 05:17:28 INFO - Successfully installed blessings 05:17:28 INFO - Cleaning up... 05:17:28 INFO - Return code: 0 05:17:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:17:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:28 INFO - Reading from file tmpfile_stdout 05:17:28 INFO - Current package versions: 05:17:28 INFO - blessings == 1.6 05:17:28 INFO - blobuploader == 1.2.4 05:17:28 INFO - docopt == 0.6.1 05:17:28 INFO - manifestparser == 1.1 05:17:28 INFO - mozInstall == 1.12 05:17:28 INFO - mozcrash == 0.17 05:17:28 INFO - mozdebug == 0.1 05:17:28 INFO - mozdevice == 0.48 05:17:28 INFO - mozfile == 1.2 05:17:28 INFO - mozhttpd == 0.7 05:17:28 INFO - mozinfo == 0.9 05:17:28 INFO - mozleak == 0.1 05:17:28 INFO - mozlog == 3.1 05:17:28 INFO - moznetwork == 0.27 05:17:28 INFO - mozprocess == 0.22 05:17:28 INFO - mozprofile == 0.28 05:17:28 INFO - mozrunner == 6.11 05:17:28 INFO - mozscreenshot == 0.1 05:17:28 INFO - mozsystemmonitor == 0.0 05:17:28 INFO - moztest == 0.7 05:17:28 INFO - mozversion == 1.4 05:17:28 INFO - psutil == 3.1.1 05:17:28 INFO - requests == 1.2.3 05:17:28 INFO - wsgiref == 0.1.2 05:17:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:28 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 0x10c638a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c648420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8259d72c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d7e00>, '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 0x10c300db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d2b20>, '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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:17:28 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:17:28 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:17:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IlOL4jQ2E/Render', 05:17:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:28 INFO - 'HOME': '/Users/cltbld', 05:17:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:28 INFO - 'LOGNAME': 'cltbld', 05:17:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:28 INFO - 'MOZ_NO_REMOTE': '1', 05:17:28 INFO - 'NO_EM_RESTART': '1', 05:17:28 INFO - 'PAGER': '/bin/cat', 05:17:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:28 INFO - 'PWD': '/builds/slave/test', 05:17:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:28 INFO - 'SHELL': '/bin/bash', 05:17:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOjbPiaZjN/Listeners', 05:17:28 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:17:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:28 INFO - 'USER': 'cltbld', 05:17:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:28 INFO - 'XPC_FLAGS': '0x0', 05:17:28 INFO - 'XPC_SERVICE_NAME': '0', 05:17:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:28 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:17:28 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:17:28 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:17:28 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:17:28 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:17:28 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:17:28 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:17:28 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:17:28 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:17:28 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:17:28 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:17:28 INFO - Cleaning up... 05:17:28 INFO - Return code: 0 05:17:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c638a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c648420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8259d72c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d7e00>, '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 0x10c300db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d2b20>, '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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:17:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:17:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IlOL4jQ2E/Render', 05:17:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:28 INFO - 'HOME': '/Users/cltbld', 05:17:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:28 INFO - 'LOGNAME': 'cltbld', 05:17:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:28 INFO - 'MOZ_NO_REMOTE': '1', 05:17:28 INFO - 'NO_EM_RESTART': '1', 05:17:28 INFO - 'PAGER': '/bin/cat', 05:17:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:28 INFO - 'PWD': '/builds/slave/test', 05:17:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:28 INFO - 'SHELL': '/bin/bash', 05:17:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOjbPiaZjN/Listeners', 05:17:28 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:17:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:28 INFO - 'USER': 'cltbld', 05:17:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:28 INFO - 'XPC_FLAGS': '0x0', 05:17:28 INFO - 'XPC_SERVICE_NAME': '0', 05:17:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:28 INFO - Downloading/unpacking jsonschema 05:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:17:31 INFO - Downloading/unpacking functools32 (from jsonschema) 05:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:31 INFO - Downloading functools32-3.2.3-2.tar.gz 05:17:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:17:31 INFO - warning: no files found matching '*.txt' 05:17:31 INFO - no previously-included directories found matching 'build' 05:17:31 INFO - no previously-included directories found matching 'dist' 05:17:31 INFO - no previously-included directories found matching '.git*' 05:17:31 INFO - Installing collected packages: jsonschema, functools32 05:17:31 INFO - Running setup.py install for functools32 05:17:31 INFO - warning: no files found matching '*.txt' 05:17:31 INFO - no previously-included directories found matching 'build' 05:17:31 INFO - no previously-included directories found matching 'dist' 05:17:31 INFO - no previously-included directories found matching '.git*' 05:17:31 INFO - Successfully installed jsonschema functools32 05:17:31 INFO - Cleaning up... 05:17:32 INFO - Return code: 0 05:17:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c638a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c648420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8259d72c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d7e00>, '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 0x10c300db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d2b20>, '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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:17:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IlOL4jQ2E/Render', 05:17:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:32 INFO - 'HOME': '/Users/cltbld', 05:17:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:32 INFO - 'LOGNAME': 'cltbld', 05:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:32 INFO - 'MOZ_NO_REMOTE': '1', 05:17:32 INFO - 'NO_EM_RESTART': '1', 05:17:32 INFO - 'PAGER': '/bin/cat', 05:17:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:32 INFO - 'PWD': '/builds/slave/test', 05:17:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:32 INFO - 'SHELL': '/bin/bash', 05:17:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOjbPiaZjN/Listeners', 05:17:32 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:17:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:32 INFO - 'USER': 'cltbld', 05:17:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:32 INFO - 'XPC_FLAGS': '0x0', 05:17:32 INFO - 'XPC_SERVICE_NAME': '0', 05:17:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:32 INFO - Downloading/unpacking flake8 05:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:17:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:17:34 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:17:34 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:17:34 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:17:34 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:17:34 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:17:34 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:17:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:17:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:17:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:17:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:17:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:17:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:17:35 INFO - no previously-included directories found matching 'docs/_build' 05:17:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:17:36 INFO - Running setup.py install for pep8 05:17:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:17:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:17:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:17:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:17:36 INFO - no previously-included directories found matching 'docs/_build' 05:17:36 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:17:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:17:36 INFO - Cleaning up... 05:17:36 INFO - Return code: 0 05:17:36 INFO - Running post-action listener: _resource_record_post_action 05:17:36 INFO - Running post-action listener: _start_resource_monitoring 05:17:36 INFO - Starting resource monitoring. 05:17:36 INFO - ##### 05:17:36 INFO - ##### Running install step. 05:17:36 INFO - ##### 05:17:36 INFO - Running pre-action listener: _resource_record_pre_action 05:17:36 INFO - Running main action method: install 05:17:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:17:36 INFO - Reading from file tmpfile_stdout 05:17:36 INFO - Detecting whether we're running mozinstall >=1.0... 05:17:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:17:36 INFO - Reading from file tmpfile_stdout 05:17:36 INFO - Output received: 05:17:36 INFO - Usage: mozinstall [options] installer 05:17:36 INFO - Options: 05:17:36 INFO - -h, --help show this help message and exit 05:17:36 INFO - -d DEST, --destination=DEST 05:17:36 INFO - Directory to install application into. [default: 05:17:36 INFO - "/builds/slave/test"] 05:17:36 INFO - --app=APP Application being installed. [default: firefox] 05:17:36 INFO - mkdir: /builds/slave/test/build/application 05:17:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:18:11 INFO - Reading from file tmpfile_stdout 05:18:11 INFO - Output received: 05:18:11 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:18:11 INFO - Running post-action listener: _resource_record_post_action 05:18:11 INFO - ##### 05:18:11 INFO - ##### Running run-tests step. 05:18:11 INFO - ##### 05:18:11 INFO - Running pre-action listener: _resource_record_pre_action 05:18:11 INFO - Running main action method: run_tests 05:18:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:18:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:18:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:18:11 INFO - 2016-04-16 05:18:11.299 screenresolution[1650:7811] starting screenresolution argv=screenresolution get 05:18:11 INFO - 2016-04-16 05:18:11.301 screenresolution[1650:7811] Display 0: 1600x1200x32@60 05:18:11 INFO - 2016-04-16 05:18:11.307 screenresolution[1651:7814] starting screenresolution argv=screenresolution list 05:18:11 INFO - Available Modes on Display 0 05:18:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:18:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:18:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:18:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:18:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:18:11 INFO - Intel Iris: 05:18:11 INFO - Chipset Model: Intel Iris 05:18:11 INFO - Type: GPU 05:18:11 INFO - Bus: Built-In 05:18:11 INFO - VRAM (Dynamic, Max): 1536 MB 05:18:11 INFO - Vendor: Intel (0x8086) 05:18:11 INFO - Device ID: 0x0a2e 05:18:11 INFO - Revision ID: 0x0009 05:18:11 INFO - Displays: 05:18:11 INFO - Display: 05:18:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:18:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:18:11 INFO - Display Serial Number: 1600x1200 60 05:18:11 INFO - Main Display: Yes 05:18:11 INFO - Mirror: Off 05:18:11 INFO - Online: Yes 05:18:11 INFO - Rotation: Supported 05:18:11 INFO - Return code: 0 05:18:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:18:11 INFO - Python 2.7.3 05:18:11 INFO - Return code: 0 05:18:11 INFO - grabbing minidump binary from tooltool 05:18:11 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:18:11 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 0x10c4d7e00>, '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 0x10c300db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c4d2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:18:11 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:18:11 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:18:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:18:11 INFO - Return code: 0 05:18:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:18:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:18:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:18:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:18:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:18:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:18:12 INFO - Return code: 0 05:18:12 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/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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:18:12 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/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0159 --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:18:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IlOL4jQ2E/Render', 05:18:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:12 INFO - 'HOME': '/Users/cltbld', 05:18:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:12 INFO - 'LOGNAME': 'cltbld', 05:18:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:18:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:12 INFO - 'MOZ_NO_REMOTE': '1', 05:18:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:18:12 INFO - 'NO_EM_RESTART': '1', 05:18:12 INFO - 'PAGER': '/bin/cat', 05:18:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:12 INFO - 'PWD': '/builds/slave/test', 05:18:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:18:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:12 INFO - 'SHELL': '/bin/bash', 05:18:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOjbPiaZjN/Listeners', 05:18:12 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 05:18:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:12 INFO - 'USER': 'cltbld', 05:18:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:12 INFO - 'XPC_FLAGS': '0x0', 05:18:12 INFO - 'XPC_SERVICE_NAME': '0', 05:18:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:12 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/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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:18:13 INFO - mozversion application_buildid: 20160416030220 05:18:13 INFO - mozversion application_changeset: a5322c766f510d2a6544b519ffcc72a19879c779 05:18:13 INFO - mozversion application_display_name: Nightly 05:18:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:18:13 INFO - mozversion application_name: Firefox 05:18:13 INFO - mozversion application_remotingname: firefox 05:18:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:18:13 INFO - mozversion application_vendor: Mozilla 05:18:13 INFO - mozversion application_version: 48.0a1 05:18:13 INFO - mozversion platform_buildid: 20160416030220 05:18:13 INFO - mozversion platform_changeset: a5322c766f510d2a6544b519ffcc72a19879c779 05:18:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:18:13 INFO - mozversion platform_version: 48.0a1 05:18:13 INFO - using testdate: 1460809093 05:18:13 INFO - actual date: 1460809093 05:18:13 INFO - starting webserver on 'localhost:49278' 05:18:13 INFO - SUITE-START | Running 2 tests 05:18:13 INFO - TEST-START | dromaeo_css 05:18:13 INFO - operating with platform_type : mac_ 05:18:13 INFO - Initialising browser for dromaeo_css test... 05:18:13 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpiQIBBl/profile http://localhost:49278/getInfo.html) 05:18:15 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 05:18:15 INFO - PROCESS | 1669 | colorDepth:24 05:18:15 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 05:18:15 INFO - PROCESS | 1669 | __metrics 05:18:15 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:18:15 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:18:16 INFO - TEST-INFO | 1669: exit 0 05:18:16 INFO - Browser initialized. 05:18:16 INFO - Running cycle 1/1 for dromaeo_css test... 05:18:16 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpiQIBBl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:18:28 INFO - PROCESS | 1673 | RSS: Main: 237498368 05:18:28 INFO - PROCESS | 1673 | 05:19:19 INFO - PROCESS | 1673 | 1460809159692 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:21:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 05:21:32 INFO - PROCESS | 1673 | RSS: Main: 357240832 05:21:32 INFO - PROCESS | 1673 | 05:24:34 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 05:24:35 INFO - PROCESS | 1673 | RSS: Main: 372772864 05:24:35 INFO - PROCESS | 1673 | 05:27:37 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 05:27:38 INFO - PROCESS | 1673 | RSS: Main: 375250944 05:27:38 INFO - PROCESS | 1673 | 05:30:40 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 05:30:41 INFO - PROCESS | 1673 | RSS: Main: 375037952 05:30:41 INFO - PROCESS | 1673 | 05:33:44 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 05:33:44 INFO - PROCESS | 1673 | RSS: Main: 342216704 05:33:44 INFO - PROCESS | 1673 | 05:36:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 05:36:47 INFO - PROCESS | 1673 | RSS: Main: 384913408 05:36:47 INFO - PROCESS | 1673 | 05:36:47 INFO - PROCESS | 1673 | __start_tp_report 05:36:47 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 05:36:47 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 05:36:47 INFO - PROCESS | 1673 | |i|pagename|runs| 05:36:47 INFO - PROCESS | 1673 | |0;dojo.html;3181.2206009527667;3459.7751146175497;3481.0775144586687;3492.2840817632386;3517.138301116227;5605.859853503654;5668.914966275504;5692.288463942007;5701.657900525966;5701.6864072476;5632.408597097307;5675.035244008518;5735.856603584913;5799.072148456248;5837.299524057108;5149.665271757342;5192.844214673566;5207.010667973092;5225.425203227642;5306.867328316785;5274.7626356814035;5305.071612467822;5325.467453254682;5343.17180836969;5385.78456861725;5154.175331946908;5167.586593072545;5242.449542797989;5265.842024739264;5274.2879711238875;5214.035403450373;5223.112070947948;5236.423993360727;5253.080710875582;5273.314469119028;5183.455737147583;5191.766370513298;5225.738713064369;5237.214417837355;5261.000409922102;5464.808731694372;5534.08687566551;5546.91679624806;5595.524380427669;5628.690422026787;6176;6185.041318595625;6188.566800323979;6204.162438070848;6223.657698826562;4895.461499235082;6239.407256310643;6240.719167637467;6257.936150854366;6406.359364063603;5196.402413722432;5684.8010319638615;5714.314282286069;5728.971355143281;5745.885082298372;6094.293597792625;6140.754369825157;6141;6142.232220972379;6147.170132032208;6047.12316714074;6067.18953987826;6119.082137679295;6125.019996800491;6132.202813634199;6198.752049918043;6251.749930002749;6252.187215661935;6258.9374106259265;6262.248530176317;6016.879662406727;6157.692115394212;6161.938380616137;6170.753169873245;6173.475254603409;6111.956402836248;6121.7474480264555;6122.032243765608;6127.785527506605;6138;4812.302216178635;4827.444843843003;4851.102546028996;4889.535494128052;4948.233023881304;4254.680898932593;4317.395564620892;4336.457942757155;4375.606195442426;4439.866803995821;5632.577556683265;5640.210370548127;5642.125470552071;5643.435656434323;5706.087026075891;5953.7023148843255;6064.545159112996;6072.726727297282;6091.482224011009;6097.603655762361;5597.888042239132;5628.887422251614;5631.662100273997;5632.8028519002455;5635.633683810539;5296.25852380667;5305.124654431976;5329.227262046981;5337.092694241988;5341.946580534222;6222.595531290452;6249.0626406039455;6260.217472815898;6283.654399008007;6294.842628934313;6225.159603453476;6254.21822272216;6263.311035785969;6273.341299163522;6278.717457714505;6311.431971122621;6338.176037115146;6356.459700925473;6360.681965901779;6363.809085727436;6057;6101.217066730222;6144.324124346408;6230.6884655767935;6232.439080482778;6104.216637118914;6108.602940808833;6113.480354169946;6146.692665366803;6221.720022598904;6052.152698622109;6070.939290607214;6083.6045657032155;6091.177691011657;6116.143739876332;6063.9696801517475;6071.392860713893;6081.787137450168;6085.1785009023215;6099.542534309856;5749;5825.2135961644635;5848.327442344185;5887.941120588739;5913.58604897653;6055.212822333068;6101.206843110367;6127.2034635498885;6130.080487926846;6133.018717005258;5885.705714714161;5890.705464726832;5940.3168635605725;5946.316173639913;5951.375105613848 05:36:47 INFO - PROCESS | 1673 | |1;ext.html;504.2285303485667;537.1218058474393;567.9521283232434;594.3996563470893;621.9892674404093;29949.550756738667;38058.23883522331;39029.41455878164;39243;39320.80339598305;41390.17219655612;41727.79136104315;41916.742497725114;41936.37095443563;42087.94780130498;8461.95769021154;9322.021187775288;9547.331686781929;9557.856632150488;9558.187554057879;8614.483131012159;11409.20135590509;11728.824067638992;11732.706682332924;11738;11404.973552380285;11498.137639677016;11512.848715128504;11513.366764827932;11587.826182607268;8522.659093636246;11398.202125851194;11423.828642570368;11518.308901465896;11564.363959982198;11391.715207119805;11547.422628868522;11558.017568506688;11568.305901645928;11587.362695051768;38728.419073713914;39237;39239.60760392388;39250.41124383137;39591.20817583661;3106.5806116174253;3303.7357011439026;3469.6877281044576;3503.8248087595775;3534.293141371736;28620.427591448264;28782.424351513062;28926.71073289261;29369.559456608167;29691.85154074216;2646.6823981122197;2731.576605626131;2741.972580274192;2750.4499100179846;2778.4582006508535;470300;471046.93429598585;472805;473195;473924.99999999657;35498.46752298718;63459.36540634627;64032;64497.67751161214;64505;36597.63402365996;36801;36814.8159259202;36914;36956.260874782354;15261.9236903817;15369;15412.152331621644;15454.459093931659;15495.690086198212;11844.940775296069;12131.696707582381;12617.369131543386;12882.87432573615;12888.646692097382;8062.55655938929;8083.797905052421;8113.269805717396;8169.877451838227;8174.836503269901;3374.3420033093803;3440.174358154075;3448.586169659657;3448.77582957107;3467.7225821934185;983.8917719050899;1061.3313612424124;1085.934843909368;1130.4912789244709;1151.83874257604;25443.10949116772;29042.854785725936;29071.12786616405;29523.409531809673;30088.69911300903;1128.3963079752293;1136.8067428537004;1162.2561560601052;1195.8086706126978;1264.9367531623382;15268;15274.694506110038;15336;15654.765178522332;15735.4492592761;586795;590945;601662;602671;602853;61759;62371.376286237464;62540.37459625345;62545;62673.05990410147;1352.1481466675932;1462.495439073518;1490.9627259318386;1504.2403586188905;1513;28993.85503072471;29529.966451174107;29568.704312956594;30268.24329391783;30291.545626815176;49501.504984949686;50308.99382012266;50366.748166258934;50603.73490662763;50608.746956264986;397654;400931;401530;401707;402704;23306.184283549996;26251.606225906246;27235;27442.862785686742;27483.862580686968;42830.71507854769;43224.783876081674;43278.350824738285;43456.34815477707;43590.56409435865;35083.12292192614;35465.11337216487;35623.10942226465;36349.63650363568;36447.635523645484;35166.296674066885;35215.47176792299;35569.82215088908;35809;37431.43852842155;35471;36162.8191859039;36324.63675363318;36531.086722832144;37186.25627487489;31646.525302120026;32684.019479415656;32889.67110328962;32945.176370590925;33362.33275334528;23996;24594.631080533447;24621.50756984886;24650.87674561616;24660.076015282317 05:36:47 INFO - PROCESS | 1673 | |2;jquery.html;377.48661819924905;379.77213671796915;383.39232316777907;388.4600405436442;393.3234836081938;14543.563693089225;14972;18061.006644634555;19431.125599348044;19445.708314375297;14910.850891491109;18446.538836529093;19018.049097545136;19087.331943381985;20089.095990680416;5657.43425657433;5851.824445266649;5901;5903.4686878180955;5903.966805808987;4859.368282123337;5387.568994480452;5460.290162278899;5787.160861675055;5809.419058094199;5648.943510564883;5703.40114288;5719.113537401709;5754.827355179351;5787.971060144693;5571.777128914839;5817.0692689169955;5835.620684655484;5840.707964601753;5846.064629659234;4869.4156701195725;4999.677472175805;5042.823501177478;5073.6955782652785;5082.212257100118;24139.396515087086;24217.878910605334;24669.87665061681;24708.50582988348;24717.50564988708;2157.9460513487134;2183.8689678619335;2229.1863469833556;2483.639872218518;2573.1894453247355;14234.43062277518;14655.853441465555;14788.795284731925;14797.852021479755;14805.925970370186;1700.4898530440819;1702.1829521829468;1766.0306382557553;1767.8232176782346;1932.4589115047809;115604.84395156025;117485.41257293745;119168;124394;127354;10836.67489975302;10973.069446723037;11088.223824332219;11090.722731931604;11098.55605775776;13719.657008574866;13762;13780.862191377957;13819.170849749047;13863.4454621814;7018.964905175441;8233;8438.45150065256;8456.915430845613;8531.701390451304;8455.74632761018;8508.10664880191;8547.74356769298;8600.655973761104;8619.051904290523;4229.133027729308;4376.7811609419405;4401.2517871961845;4406.647468202536;4407.625351845129;1471.8380978092616;1818.3908390689267;1839.420582516503;1844.7786265648208;1866.3094654977745;773.4199350487022;815.8490679224362;818.414833394126;965.1747755668943;1014.1937159957852;17922;18338.633227335646;19632.803671963098;20763.480912977295;20940;977.4868194198103;1000.6791035832777;1005.8843233028296;1044.6865940217904;1054.5254635414094;8518.829623407497;8805.251553618014;10022.148117410103;10383.376997380181;10455.16358691303;617624;628178;630885;631463;641885;75625.86561201587;78658.6067069661;126136;128491;131570.34214829057;998.5257002923698;1001.5893483671659;1037.870266216723;1041.250299784154;1055.0873494427265;16660.916695416447;17281.39515116965;17640.294388224327;17822.19800109024;17985.820141798413;51349.48650513447;51417.228741569656;52133.7393313031;52296.738516307174;52960.735196323774;73384.89922651058;76035;77678.22321776937;78848;79213.60393197997;7529.171791103084;7586.6206689664195;7618.123915749759;7799.49303295284;8010.198980101944;9825.263105267277;10473.266871318934;11971.580994665144;12002.879971200176;12220.938895305822;10574;10620.893791062299;10674.252802303765;10803.513841876977;10827.917208279108;10298.3820970742;10497.317674351143;10519;10519.631812886511;10829.29609575375;8668.869966950628;10519.94740026295;10629.521671524955;10763.838542422027;11058.668239952814;7600.391968642385;7712.267334603203;7730.845383092419;7781.844363112819;8016.478928869605;8748.256398206224;10596.364218146937;10680.092192163753;10876.238663293494;10883.346999180074 05:36:47 INFO - PROCESS | 1673 | |3;mootools.html;1439.9496017639385;1601.367459853358;1601.8478244566766;1874.2409324223695;1998.530345368837;7908.2487163719425;8627.827443451128;8791.64833406663;8796.516191609475;8798.428102173362;8420.494770313786;8655.783605409852;8668.8699669505;8671.393002489829;8688.218060374566;2087.8225350845196;2130.968035479469;2208.0284674743143;2209.1163534586135;2431.927042188728;2204.559088182362;2363.1374548289837;2441.0357908626083;2451.497443024176;2455.1897873701723;2390;2403.194929698553;2408.5423769483828;2410.4693519615084;2415.649730789043;2109.24067335759;2376.310869847742;2389.629607410837;2392.1986134644917;2395.748446413136;2345.835791494596;2426.162973774045;2435.111184417675;2435.1111844176926;2435.5494233566847;7515.511491753018;8076.030876294823;8504.532250726208;8590.14098590136;8598.140185981414;5088.516590923857;5259.447757985395;5266.131088370415;5270.841874743764;5281.551068159211;3294.637589865113;3385.9492107618407;3450.4996775467416;3467.49721290414;3473.94789078164;984.4437892590663;996.3473924579412;998.3710262534629;1005.3615953869346;1010.3079390617451;11565.190436669436;11573.189876708633;11575.189736718348;11580.884191158066;11601.76796464066;4490.191765482245;4507.706999045052;4720.5515476030405;4738.005018945991;4750.548697873696;9763.853542196874;9778.853317200248;9784.510774461249;9787.804243915081;9807.509624518889;5311.52196302327;5314.654547454403;5315.202719592092;5352.357717073898;5352.91970620441;4793.520647935178;4827.951720482821;4844.491328410537;4885.60915126789;4887.926681099786;3812.3328417526823;3852.210296889131;3897.4738410314812;3918.294706952776;3932.2528719543197;1267.4930027988694;1286.1832736212386;1288.1176394170025;1295.695511554784;1297.072593095922;859.6002858670623;867.9262262707614;871.7471933139445;914.2584568907141;917.9724608261763;5183.255924499186;5220.921591413485;5238.869028274324;5325.707086110301;5333.013392522395;379.6052105809982;428.60836532150523;432.9870103896888;442.2724618003431;446.00540794029496;3654.8720794772057;3797.411401232813;3951;4018.9397159042637;4025.5571887092397;10233.692989210336;10763.623273185402;10902.182336324808;10904.07315378186;10919.290246133976;10562.683119506428;10623.893761062445;10850.945745271223;10860.294080884874;10904.236703430684;8071.636776345079;8182.18178182189;8205.671773129128;8222.62998165084;8255.669773209125;5612.298462692163;5711.628744131618;5758.942410576008;5819.5344372450945;5845.3277873045145;9899.901000990187;9988;9990.400575965465;10051.296409251283;10093.495325233856;7905.802354940943;9260.629574817202;9683.370580912218;10295.794084118426;10297.588096476056;7271.054762880792;7336.633168341668;7437.70249190048;7441.255874412515;7448.553086814808;7905.604719764104;7947.761567152995;8017.5991200438575;8045.919540804517;8145.714899978472;7292.854142917218;7390.03929489163;7418.8145296365865;7458.627068646654;7495.250474952461;6897.482688798461;6937.202221744564;6941.375276225164;7089.397401220747;7117;6709.865802684017;6715.160604924385;6769.559978601375;6770.119884414995;6800.149981252343;4584.88537786558;4613;4799.448063472745;4879.878003049952;4960.355153829979;4804.039192161512;4827.686200396963;4853.0779151961015;4866.732329721758;4921.990991846591 05:36:47 INFO - PROCESS | 1673 | |4;prototype.html;306.86497940905997;307.8106964217007;315.3267773303995;315.83576540199095;320.5944480232508;5815.418458154182;5858.2677165354335;5882.705864706758;5911.172435858984;5926.940730592687;5062.569681577071;5416.295881535405;5602.915956260659;5844.26946631671;5853.7951171709;3675.8162091895365;4264.8507302244425;4350.8687741187205;4471.686981911267;4487.416635837353;4189.958100418988;4391.209582275189;4439.889002774924;4452.397137030666;4470.150671372445;3397.96602033979;3998.4402183694306;4090;4434.979954610441;4457.866264012068;3890.338642430765;4042.908414728022;4057.2899742544937;4105.486814148217;4353.825846966118;3799.601041890617;3813.008617759375;3820.3887378019385;3832.8118283332255;3856.5291237885845;4384.693071484998;5541.667499950016;5565.833025009216;5578.721063946787;5586.972065139689;4179.2059508693255;4778.4982576829625;4902.3872015998;5044.066847633199;5065.924011139836;5328.387235467893;5666.46168613981;6081.847953801146;6154.5384096192965;6167.845803854894;4206.747595144301;4211.831526738927;4224.366345048267;4225.091605304843;4229.957700423018;4996.975015124937;4998.600111991032;5670.945274129018;6465.74137034518;6540.607563546202;4122.660644877658;4138.379243113557;4141.275276826544;4154.231467178582;4167.874963751093;4191.847242008411;4227.534971153171;4233.248388547408;4245.80893859777;4251.553586873396;4393.406890069864;4480.619147372445;4673.509281525459;4974.179260422062;4980.726060066731;5373.838784836461;6191.81424557264;6212.223472065992;6252.96826023701;6268.4671802897265;1436.741386550431;1455.5269537400388;1456.6139972907188;1459.4162335065846;1469.1919444305588;910.9726708198765;951.5194826612517;953.0517135450133;963.8506031565119;970.7573106723319;5450.509454149145;5695.373508914016;5730.587447665274;5746.482816546531;5758.942410575924;6318.747250109944;6326.841828954313;6334.651594162273;6356.809295721136;6360.554761166766;5628.641139271972;5672.432756724294;5694.857628559236;5733.757019805397;5761.85595360111;3277.311764529428;3308.2721801203697;3332.7667063305335;3335.1328654549507;3341.3818443588016;6238.539444297258;6562.639054851934;6612.338766123349;6612.867742645217;6623.370779775913;5396.055690254189;5982;6369.649669268235;6430.196225471816;6464.022702519034;6207.81376558704;6511.706973186218;6539.346065393518;6545.789029029645;6591.6374599397495;5206.427292997843;6283.250825494804;6298.1812364392335;6299.464545513682;6330.651814150266;6174.25908890936;6230.875382492234;6232.657203853651;6237.656928868956;6239.719212635533;5488.423715509974;6036.788712395045;6554;6789.93210067893;6797.796066118024;3712.814359281971;4163.500379954425;4166;4171.3325867860995;4204.747715137101;6145.569810113249;6187.350328215653;6444.226692796707;6454.580452270588;6455.419012288917;5008.799648014038;5969.134475501115;6189.628622282497;6279.246490421178;6283.434490895841;5164.638475306692;5499.780008799763;6219.968900155471;6245.406686364788;6254.374562543891;5674.375818659862;6088.756449741961;6097.451229389376;6106.572539922163;6124.785632503019;4667.8599642010795;5938.317093534299;5995.280566332068;6219.067139929047;6228;5080.288759573737;5767.250257466502;5792.594518383673;6084.513238940963;6102.223488312896 05:36:47 INFO - PROCESS | 1673 | |5;yui.html;614.5421660862661;660.7324033766326;661.9503537234707;665.7802925034739;667.3393340592813;14815.33331000105;15220.695586088299;15244;15251.847481525168;15281.541553753392;13937;14550.345234464448;14555.927220363883;14556.417743290256;14579.635509112277;729.5112274775913;746.7871656577877;906.9932375063674;913.2785099771173;925.5557332480413;884.6726711116863;925.6112432778249;927.2813569483636;927.378656300277;930.9898759845541;964.6912987843892;975.8731364922586;976.9413835169912;977.8679878216457;979.5102448775613;763.5380594740213;918.8897332320098;963.5712108111893;965.5558443116175;970.0832713086137;719.0580339754886;719.5538765965083;719.5754504842116;720.79097061852;721.1346384338815;15313.387464501406;15396.384144634201;15407.845921540753;15439.536813895602;15441.382344706199;12278.938605307005;12314.384280785926;12373;12499.000399840046;12639.368031598475;9880.505974701236;10065.094141527225;10379.377237365783;10380.584776608926;10807.83788243177;3441.63862794408;3496.5629296337956;3588.3361578108133;3607.9458808117897;3684.797336146511;83195;84486.57756711177;84953;85169.72245416323;86314;9080.40977336471;9257.629694812267;9320.906790932004;9407.623695052258;9478.004809495042;21066.946652667306;21157.894210529157;21177.835219062792;21186.682199767016;21201;5793.536517078624;6824.7952561423235;6871.072405225331;6884.793456196222;6942.708906060988;4066.694997875112;4089.0595163112653;4098.877033688994;4153.688473364449;4153.7507749535125;3552.8401221945073;3668.669819716185;3672.137047793766;3678.0988657779017;3685.94471082934;600.8708127752554;619.1053927075333;625.0249610607467;649.1172006071739;655.2366493035655;512.743628185907;566.7506297229206;585.5783835638333;594.6372712645283;594.9702514874325;7599.164091949881;7658.195889431529;7884.881726774103;7897.763067107996;8183.304419124322;542.5361316074778;563.9989019490355;578.2557937355673;594.3462191589217;603.3513972479602;3651.087228192952;3719.9070023249637;3787.9242415152094;3960.3663413853646;3971.3050216212623;80717;81995.5900220507;82026.58986705147;83140.18018738751;83335.583322083;67809;68203;68600.3139968604;68954.9656755149;68984.65507672429;58290.708546457;58335.124973125436;59130.70434647799;59419.108713369336;59704.70147649234;7290.781276561712;7323.9267607323245;7506.1743208248145;7518.586477743776;7531.962340188483;41205.587944120176;41222.58777412307;41365.37951930663;41676.58323416727;43067.49263775878;54435;54967.725161373935;56149;58883.70558147182;58965.41034589771;9702.563384647567;9785.07041831025;9899.406035637596;10204.693859184237;10456.058954694114;10837.458127093456;10843.132549395872;11130.833037504606;11311.490982905954;11597.304161750322;9700.75748106303;9777.364471309342;10106.494675266355;10158.238132140266;10158.695239142837;10245.436500992517;10365.689029329133;10604.469776511298;10624.309419887682;10664.520096595827;9830.262730295399;9852.458114803754;9958.203343732628;9973.351732137387;10032.197424206191;8741.34439916996;8798.208161265517;8929.330300227379;8941.910580894108;9014.143656352637;8378.03652579961;8513.233808957224;8528.658853645784;8628;8754.430961987451 05:36:47 INFO - PROCESS | 1673 | __end_tp_report 05:36:47 INFO - PROCESS | 1673 | __start_cc_report 05:36:47 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1158 05:36:47 INFO - PROCESS | 1673 | __end_cc_report 05:36:47 INFO - PROCESS | 1673 | __startTimestamp1460810207933__endTimestamp 05:36:47 INFO - PROCESS | 1673 | 05:36:47 INFO - PROCESS | 1673 | ------- Summary: start ------- 05:36:47 INFO - PROCESS | 1673 | Number of tests: 6 05:36:47 INFO - PROCESS | 1673 | 05:36:47 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5704.07 Median:5913.59 stddev:624.75 (10.6%) stddev-sans-first:594.49 05:36:47 INFO - PROCESS | 1673 | Values: 3181.2 3459.8 3481.1 3492.3 3517.1 5605.9 5668.9 5692.3 5701.7 5701.7 5632.4 5675.0 5735.9 5799.1 5837.3 5149.7 5192.8 5207.0 5225.4 5306.9 5274.8 5305.1 5325.5 5343.2 5385.8 5154.2 5167.6 5242.4 5265.8 5274.3 5214.0 5223.1 5236.4 5253.1 5273.3 5183.5 5191.8 5225.7 5237.2 5261.0 5464.8 5534.1 5546.9 5595.5 5628.7 6176.0 6185.0 6188.6 6204.2 6223.7 4895.5 6239.4 6240.7 6257.9 6406.4 5196.4 5684.8 5714.3 5729.0 5745.9 6094.3 6140.8 6141.0 6142.2 6147.2 6047.1 6067.2 6119.1 6125.0 6132.2 6198.8 6251.7 6252.2 6258.9 6262.2 6016.9 6157.7 6161.9 6170.8 6173.5 6112.0 6121.7 6122.0 6127.8 6138.0 4812.3 4827.4 4851.1 4889.5 4948.2 4254.7 4317.4 4336.5 4375.6 4439.9 5632.6 5640.2 5642.1 5643.4 5706.1 5953.7 6064.5 6072.7 6091.5 6097.6 5597.9 5628.9 5631.7 5632.8 5635.6 5296.3 5305.1 5329.2 5337.1 5341.9 6222.6 6249.1 6260.2 6283.7 6294.8 6225.2 6254.2 6263.3 6273.3 6278.7 6311.4 6338.2 6356.5 6360.7 6363.8 6057.0 6101.2 6144.3 6230.7 6232.4 6104.2 6108.6 6113.5 6146.7 6221.7 6052.2 6070.9 6083.6 6091.2 6116.1 6064.0 6071.4 6081.8 6085.2 6099.5 5749.0 5825.2 5848.3 5887.9 5913.6 6055.2 6101.2 6127.2 6130.1 6133.0 5885.7 5890.7 5940.3 5946.3 5951.4 05:36:47 INFO - PROCESS | 1673 | 05:36:47 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:62164.90 Median:3486.76 stddev:132558.73 (3801.8%) stddev-sans-first:132849.75 05:36:47 INFO - PROCESS | 1673 | Values: 504.2 537.1 568.0 594.4 622.0 29949.6 38058.2 39029.4 39243.0 39320.8 41390.2 41727.8 41916.7 41936.4 42087.9 8462.0 9322.0 9547.3 9557.9 9558.2 8614.5 11409.2 11728.8 11732.7 11738.0 11405.0 11498.1 11512.8 11513.4 11587.8 8522.7 11398.2 11423.8 11518.3 11564.4 11391.7 11547.4 11558.0 11568.3 11587.4 38728.4 39237.0 39239.6 39250.4 39591.2 3106.6 3303.7 3469.7 3503.8 3534.3 28620.4 28782.4 28926.7 29369.6 29691.9 2646.7 2731.6 2742.0 2750.4 2778.5 470300.0 471046.9 472805.0 473195.0 473925.0 35498.5 63459.4 64032.0 64497.7 64505.0 36597.6 36801.0 36814.8 36914.0 36956.3 15261.9 15369.0 15412.2 15454.5 15495.7 11844.9 12131.7 12617.4 12882.9 12888.6 8062.6 8083.8 8113.3 8169.9 8174.8 3374.3 3440.2 3448.6 3448.8 3467.7 983.9 1061.3 1085.9 1130.5 1151.8 25443.1 29042.9 29071.1 29523.4 30088.7 1128.4 1136.8 1162.3 1195.8 1264.9 15268.0 15274.7 15336.0 15654.8 15735.4 586795.0 590945.0 601662.0 602671.0 602853.0 61759.0 62371.4 62540.4 62545.0 62673.1 1352.1 1462.5 1491.0 1504.2 1513.0 28993.9 29530.0 29568.7 30268.2 30291.5 49501.5 50309.0 50366.7 50603.7 50608.7 397654.0 400931.0 401530.0 401707.0 402704.0 23306.2 26251.6 27235.0 27442.9 27483.9 42830.7 43224.8 43278.4 43456.3 43590.6 35083.1 35465.1 35623.1 36349.6 36447.6 35166.3 35215.5 35569.8 35809.0 37431.4 35471.0 36162.8 36324.6 36531.1 37186.3 31646.5 32684.0 32889.7 32945.2 33362.3 23996.0 24594.6 24621.5 24650.9 24660.1 05:36:47 INFO - PROCESS | 1673 | 05:36:47 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:35024.81 Median:24178.64 stddev:104635.60 (432.8%) stddev-sans-first:104896.79 05:36:47 INFO - PROCESS | 1673 | Values: 377.5 379.8 383.4 388.5 393.3 14543.6 14972.0 18061.0 19431.1 19445.7 14910.9 18446.5 19018.0 19087.3 20089.1 5657.4 5851.8 5901.0 5903.5 5904.0 4859.4 5387.6 5460.3 5787.2 5809.4 5648.9 5703.4 5719.1 5754.8 5788.0 5571.8 5817.1 5835.6 5840.7 5846.1 4869.4 4999.7 5042.8 5073.7 5082.2 24139.4 24217.9 24669.9 24708.5 24717.5 2157.9 2183.9 2229.2 2483.6 2573.2 14234.4 14655.9 14788.8 14797.9 14805.9 1700.5 1702.2 1766.0 1767.8 1932.5 115604.8 117485.4 119168.0 124394.0 127354.0 10836.7 10973.1 11088.2 11090.7 11098.6 13719.7 13762.0 13780.9 13819.2 13863.4 7019.0 8233.0 8438.5 8456.9 8531.7 8455.7 8508.1 8547.7 8600.7 8619.1 4229.1 4376.8 4401.3 4406.6 4407.6 1471.8 1818.4 1839.4 1844.8 1866.3 773.4 815.8 818.4 965.2 1014.2 17922.0 18338.6 19632.8 20763.5 20940.0 977.5 1000.7 1005.9 1044.7 1054.5 8518.8 8805.3 10022.1 10383.4 10455.2 617624.0 628178.0 630885.0 631463.0 641885.0 75625.9 78658.6 126136.0 128491.0 131570.3 998.5 1001.6 1037.9 1041.3 1055.1 16660.9 17281.4 17640.3 17822.2 17985.8 51349.5 51417.2 52133.7 52296.7 52960.7 73384.9 76035.0 77678.2 78848.0 79213.6 7529.2 7586.6 7618.1 7799.5 8010.2 9825.3 10473.3 11971.6 12002.9 12220.9 10574.0 10620.9 10674.3 10803.5 10827.9 10298.4 10497.3 10519.0 10519.6 10829.3 8668.9 10519.9 10629.5 10763.8 11058.7 7600.4 7712.3 7730.8 7781.8 8016.5 8748.3 10596.4 10680.1 10876.2 10883.3 05:36:47 INFO - PROCESS | 1673 | 05:36:47 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5597.06 Median:4873.31 stddev:3171.64 (65.1%) stddev-sans-first:3165.15 05:36:47 INFO - PROCESS | 1673 | Values: 1439.9 1601.4 1601.8 1874.2 1998.5 7908.2 8627.8 8791.6 8796.5 8798.4 8420.5 8655.8 8668.9 8671.4 8688.2 2087.8 2131.0 2208.0 2209.1 2431.9 2204.6 2363.1 2441.0 2451.5 2455.2 2390.0 2403.2 2408.5 2410.5 2415.6 2109.2 2376.3 2389.6 2392.2 2395.7 2345.8 2426.2 2435.1 2435.1 2435.5 7515.5 8076.0 8504.5 8590.1 8598.1 5088.5 5259.4 5266.1 5270.8 5281.6 3294.6 3385.9 3450.5 3467.5 3473.9 984.4 996.3 998.4 1005.4 1010.3 11565.2 11573.2 11575.2 11580.9 11601.8 4490.2 4507.7 4720.6 4738.0 4750.5 9763.9 9778.9 9784.5 9787.8 9807.5 5311.5 5314.7 5315.2 5352.4 5352.9 4793.5 4828.0 4844.5 4885.6 4887.9 3812.3 3852.2 3897.5 3918.3 3932.3 1267.5 1286.2 1288.1 1295.7 1297.1 859.6 867.9 871.7 914.3 918.0 5183.3 5220.9 5238.9 5325.7 5333.0 379.6 428.6 433.0 442.3 446.0 3654.9 3797.4 3951.0 4018.9 4025.6 10233.7 10763.6 10902.2 10904.1 10919.3 10562.7 10623.9 10850.9 10860.3 10904.2 8071.6 8182.2 8205.7 8222.6 8255.7 5612.3 5711.6 5758.9 5819.5 5845.3 9899.9 9988.0 9990.4 10051.3 10093.5 7905.8 9260.6 9683.4 10295.8 10297.6 7271.1 7336.6 7437.7 7441.3 7448.6 7905.6 7947.8 8017.6 8045.9 8145.7 7292.9 7390.0 7418.8 7458.6 7495.3 6897.5 6937.2 6941.4 7089.4 7117.0 6709.9 6715.2 6769.6 6770.1 6800.1 4584.9 4613.0 4799.4 4879.9 4960.4 4804.0 4827.7 4853.1 4866.7 4922.0 05:36:47 INFO - PROCESS | 1673 | 05:36:47 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:4959.05 Median:5615.78 stddev:1550.41 (27.6%) stddev-sans-first:1514.93 05:36:47 INFO - PROCESS | 1673 | Values: 306.9 307.8 315.3 315.8 320.6 5815.4 5858.3 5882.7 5911.2 5926.9 5062.6 5416.3 5602.9 5844.3 5853.8 3675.8 4264.9 4350.9 4471.7 4487.4 4190.0 4391.2 4439.9 4452.4 4470.2 3398.0 3998.4 4090.0 4435.0 4457.9 3890.3 4042.9 4057.3 4105.5 4353.8 3799.6 3813.0 3820.4 3832.8 3856.5 4384.7 5541.7 5565.8 5578.7 5587.0 4179.2 4778.5 4902.4 5044.1 5065.9 5328.4 5666.5 6081.8 6154.5 6167.8 4206.7 4211.8 4224.4 4225.1 4230.0 4997.0 4998.6 5670.9 6465.7 6540.6 4122.7 4138.4 4141.3 4154.2 4167.9 4191.8 4227.5 4233.2 4245.8 4251.6 4393.4 4480.6 4673.5 4974.2 4980.7 5373.8 6191.8 6212.2 6253.0 6268.5 1436.7 1455.5 1456.6 1459.4 1469.2 911.0 951.5 953.1 963.9 970.8 5450.5 5695.4 5730.6 5746.5 5758.9 6318.7 6326.8 6334.7 6356.8 6360.6 5628.6 5672.4 5694.9 5733.8 5761.9 3277.3 3308.3 3332.8 3335.1 3341.4 6238.5 6562.6 6612.3 6612.9 6623.4 5396.1 5982.0 6369.6 6430.2 6464.0 6207.8 6511.7 6539.3 6545.8 6591.6 5206.4 6283.3 6298.2 6299.5 6330.7 6174.3 6230.9 6232.7 6237.7 6239.7 5488.4 6036.8 6554.0 6789.9 6797.8 3712.8 4163.5 4166.0 4171.3 4204.7 6145.6 6187.4 6444.2 6454.6 6455.4 5008.8 5969.1 6189.6 6279.2 6283.4 5164.6 5499.8 6220.0 6245.4 6254.4 5674.4 6088.8 6097.5 6106.6 6124.8 4667.9 5938.3 5995.3 6219.1 6228.0 5080.3 5767.3 5792.6 6084.5 6102.2 05:36:47 INFO - PROCESS | 1673 | 05:36:47 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:16778.72 Median:602.11 stddev:23239.58 (3859.7%) stddev-sans-first:23273.08 05:36:47 INFO - PROCESS | 1673 | Values: 614.5 660.7 662.0 665.8 667.3 14815.3 15220.7 15244.0 15251.8 15281.5 13937.0 14550.3 14555.9 14556.4 14579.6 729.5 746.8 907.0 913.3 925.6 884.7 925.6 927.3 927.4 931.0 964.7 975.9 976.9 977.9 979.5 763.5 918.9 963.6 965.6 970.1 719.1 719.6 719.6 720.8 721.1 15313.4 15396.4 15407.8 15439.5 15441.4 12278.9 12314.4 12373.0 12499.0 12639.4 9880.5 10065.1 10379.4 10380.6 10807.8 3441.6 3496.6 3588.3 3607.9 3684.8 83195.0 84486.6 84953.0 85169.7 86314.0 9080.4 9257.6 9320.9 9407.6 9478.0 21066.9 21157.9 21177.8 21186.7 21201.0 5793.5 6824.8 6871.1 6884.8 6942.7 4066.7 4089.1 4098.9 4153.7 4153.8 3552.8 3668.7 3672.1 3678.1 3685.9 600.9 619.1 625.0 649.1 655.2 512.7 566.8 585.6 594.6 595.0 7599.2 7658.2 7884.9 7897.8 8183.3 542.5 564.0 578.3 594.3 603.4 3651.1 3719.9 3787.9 3960.4 3971.3 80717.0 81995.6 82026.6 83140.2 83335.6 67809.0 68203.0 68600.3 68955.0 68984.7 58290.7 58335.1 59130.7 59419.1 59704.7 7290.8 7323.9 7506.2 7518.6 7532.0 41205.6 41222.6 41365.4 41676.6 43067.5 54435.0 54967.7 56149.0 58883.7 58965.4 9702.6 9785.1 9899.4 10204.7 10456.1 10837.5 10843.1 11130.8 11311.5 11597.3 9700.8 9777.4 10106.5 10158.2 10158.7 10245.4 10365.7 10604.5 10624.3 10664.5 9830.3 9852.5 9958.2 9973.4 10032.2 8741.3 8798.2 8929.3 8941.9 9014.1 8378.0 8513.2 8528.7 8628.0 8754.4 05:36:47 INFO - PROCESS | 1673 | -------- Summary: end -------- 05:36:47 INFO - PROCESS | 1673 | 05:36:48 INFO - TEST-INFO | 1673: exit 0 05:36:48 INFO - TEST-OK | dromaeo_css | took 1115469ms 05:36:48 INFO - TEST-START | kraken 05:36:48 INFO - operating with platform_type : mac_ 05:36:48 INFO - Initialising browser for kraken test... 05:36:48 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpCDTHYu/profile http://localhost:49278/getInfo.html) 05:36:50 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 05:36:50 INFO - PROCESS | 1680 | colorDepth:24 05:36:50 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 05:36:50 INFO - PROCESS | 1680 | __metrics 05:36:51 INFO - PROCESS | 1680 | 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:36:51 ERROR - PROCESS | 1680 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:36:51 INFO - TEST-INFO | 1680: exit 0 05:36:51 INFO - Browser initialized. 05:36:51 INFO - Running cycle 1/1 for kraken test... 05:36:51 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpCDTHYu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:03 INFO - PROCESS | 1683 | RSS: Main: 237715456 05:37:03 INFO - PROCESS | 1683 | 05:37:06 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 05:37:07 INFO - PROCESS | 1683 | RSS: Main: 286314496 05:37:07 INFO - PROCESS | 1683 | 05:37:09 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 05:37:10 INFO - PROCESS | 1683 | RSS: Main: 299020288 05:37:10 INFO - PROCESS | 1683 | 05:37:14 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 05:37:14 INFO - PROCESS | 1683 | RSS: Main: 384221184 05:37:14 INFO - PROCESS | 1683 | 05:37:16 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 05:37:17 INFO - PROCESS | 1683 | RSS: Main: 285163520 05:37:17 INFO - PROCESS | 1683 | 05:37:19 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:37:20 INFO - PROCESS | 1683 | RSS: Main: 289222656 05:37:20 INFO - PROCESS | 1683 | 05:37:22 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 05:37:23 INFO - PROCESS | 1683 | RSS: Main: 324943872 05:37:23 INFO - PROCESS | 1683 | 05:37:26 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 05:37:26 INFO - PROCESS | 1683 | RSS: Main: 325816320 05:37:26 INFO - PROCESS | 1683 | 05:37:29 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 05:37:30 INFO - PROCESS | 1683 | RSS: Main: 325627904 05:37:30 INFO - PROCESS | 1683 | 05:37:31 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:37:32 INFO - PROCESS | 1683 | RSS: Main: 319733760 05:37:32 INFO - PROCESS | 1683 | 05:37:34 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:37:35 INFO - PROCESS | 1683 | RSS: Main: 326746112 05:37:35 INFO - PROCESS | 1683 | 05:37:37 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:37:37 INFO - PROCESS | 1683 | RSS: Main: 329449472 05:37:37 INFO - PROCESS | 1683 | 05:37:40 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:37:41 INFO - PROCESS | 1683 | RSS: Main: 334290944 05:37:41 INFO - PROCESS | 1683 | 05:37:44 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:37:44 INFO - PROCESS | 1683 | RSS: Main: 331882496 05:37:44 INFO - PROCESS | 1683 | 05:37:46 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 05:37:46 INFO - PROCESS | 1683 | RSS: Main: 396648448 05:37:46 INFO - PROCESS | 1683 | 05:37:46 INFO - PROCESS | 1683 | __start_tp_report 05:37:46 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 05:37:46 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 05:37:46 INFO - PROCESS | 1683 | |i|pagename|runs| 05:37:46 INFO - PROCESS | 1683 | |0;ai-astar;113;109;112;112;112;114;118;112;114;113 05:37:46 INFO - PROCESS | 1683 | |1;audio-beat-detection;157;141;144;169;140;147;164;109;195;117 05:37:46 INFO - PROCESS | 1683 | |2;audio-dft;187;187;189;182;186;183;187;184;185;186 05:37:46 INFO - PROCESS | 1683 | |3;audio-fft;78;71;85;90;46;50;75;110;90;74 05:37:46 INFO - PROCESS | 1683 | |4;audio-oscillator;91;94;133;77;77;79;128;79;77;124 05:37:46 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;91;87;92;89;91;87;93;129;91;88 05:37:46 INFO - PROCESS | 1683 | |6;imaging-darkroom;95;96;95;96;95;96;96;97;96;95 05:37:46 INFO - PROCESS | 1683 | |7;imaging-desaturate;83;80;112;139;84;83;86;84;86;85 05:37:46 INFO - PROCESS | 1683 | |8;json-parse-financial;58;60;58;57;59;58;57;77;60;58 05:37:46 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;63;66;72;76;72;66;67;67;69;67 05:37:46 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;80;83;82;82;80;80;94;106;104;82 05:37:46 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;143;141;138;139;147;156;140;138;184;155 05:37:46 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;169;170;170;181;168;169;170;168;168;169 05:37:46 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;70;67;64;79;93;91;75;70;67;66 05:37:46 INFO - PROCESS | 1683 | __end_tp_report 05:37:46 INFO - PROCESS | 1683 | __start_cc_report 05:37:46 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,2041 05:37:46 INFO - PROCESS | 1683 | __end_cc_report 05:37:46 INFO - PROCESS | 1683 | __startTimestamp1460810266993__endTimestamp 05:37:46 INFO - PROCESS | 1683 | 05:37:46 INFO - PROCESS | 1683 | ------- Summary: start ------- 05:37:47 INFO - PROCESS | 1683 | Number of tests: 14 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:2.28 (2.0%) stddev-sans-first:2.42 05:37:47 INFO - PROCESS | 1683 | Values: 113.0 109.0 112.0 112.0 112.0 114.0 118.0 112.0 114.0 113.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:148.30 Median:152.00 stddev:24.94 (16.4%) stddev-sans-first:26.25 05:37:47 INFO - PROCESS | 1683 | Values: 157.0 141.0 144.0 169.0 140.0 147.0 164.0 109.0 195.0 117.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:185.60 Median:186.50 stddev:2.12 (1.1%) stddev-sans-first:2.19 05:37:47 INFO - PROCESS | 1683 | Values: 187.0 187.0 189.0 182.0 186.0 183.0 187.0 184.0 185.0 186.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:76.90 Median:76.50 stddev:18.95 (24.8%) stddev-sans-first:20.09 05:37:47 INFO - PROCESS | 1683 | Values: 78.0 71.0 85.0 90.0 46.0 50.0 75.0 110.0 90.0 74.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:78.00 stddev:23.25 (29.8%) stddev-sans-first:24.60 05:37:47 INFO - PROCESS | 1683 | Values: 91.0 94.0 133.0 77.0 77.0 79.0 128.0 79.0 77.0 124.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:93.80 Median:91.00 stddev:12.54 (13.8%) stddev-sans-first:13.26 05:37:47 INFO - PROCESS | 1683 | Values: 91.0 87.0 92.0 89.0 91.0 87.0 93.0 129.0 91.0 88.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 05:37:47 INFO - PROCESS | 1683 | Values: 95.0 96.0 95.0 96.0 95.0 96.0 96.0 97.0 96.0 95.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:84.00 stddev:18.75 (22.3%) stddev-sans-first:19.59 05:37:47 INFO - PROCESS | 1683 | Values: 83.0 80.0 112.0 139.0 84.0 83.0 86.0 84.0 86.0 85.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:58.50 stddev:6.00 (10.3%) stddev-sans-first:6.31 05:37:47 INFO - PROCESS | 1683 | Values: 58.0 60.0 58.0 57.0 59.0 58.0 57.0 77.0 60.0 58.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:68.00 stddev:3.81 (5.6%) stddev-sans-first:3.48 05:37:47 INFO - PROCESS | 1683 | Values: 63.0 66.0 72.0 76.0 72.0 66.0 67.0 67.0 69.0 67.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:82.00 stddev:10.20 (12.4%) stddev-sans-first:10.47 05:37:47 INFO - PROCESS | 1683 | Values: 80.0 83.0 82.0 82.0 80.0 80.0 94.0 106.0 104.0 82.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:145.00 stddev:14.26 (9.8%) stddev-sans-first:15.00 05:37:47 INFO - PROCESS | 1683 | Values: 143.0 141.0 138.0 139.0 147.0 156.0 140.0 138.0 184.0 155.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:169.50 stddev:3.88 (2.3%) stddev-sans-first:4.09 05:37:47 INFO - PROCESS | 1683 | Values: 169.0 170.0 170.0 181.0 168.0 169.0 170.0 168.0 168.0 169.0 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:72.50 stddev:10.38 (14.3%) stddev-sans-first:10.90 05:37:47 INFO - PROCESS | 1683 | Values: 70.0 67.0 64.0 79.0 93.0 91.0 75.0 70.0 67.0 66.0 05:37:47 INFO - PROCESS | 1683 | -------- Summary: end -------- 05:37:47 INFO - PROCESS | 1683 | 05:37:47 INFO - TEST-INFO | 1683: exit 0 05:37:47 INFO - TEST-OK | kraken | took 59240ms 05:37:47 INFO - SUITE-END | took 1174s 05:37:48 INFO - Completed test suite (00:19:35) 05:37:48 INFO - javascript benchmark 05:37:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3181.2206009527667, 3459.7751146175497, 3481.0775144586687, 3492.2840817632386, 3517.138301116227, 5605.859853503654, 5668.914966275504, 5692.288463942007, 5701.657900525966, 5701.6864072476, 5632.408597097307, 5675.035244008518, 5735.856603584913, 5799.072148456248, 5837.299524057108, 5149.665271757342, 5192.844214673566, 5207.010667973092, 5225.425203227642, 5306.867328316785, 5274.7626356814035, 5305.071612467822, 5325.467453254682, 5343.17180836969, 5385.78456861725, 5154.175331946908, 5167.586593072545, 5242.449542797989, 5265.842024739264, 5274.2879711238875, 5214.035403450373, 5223.112070947948, 5236.423993360727, 5253.080710875582, 5273.314469119028, 5183.455737147583, 5191.766370513298, 5225.738713064369, 5237.214417837355, 5261.000409922102, 5464.808731694372, 5534.08687566551, 5546.91679624806, 5595.524380427669, 5628.690422026787, 6176.0, 6185.041318595625, 6188.566800323979, 6204.162438070848, 6223.657698826562, 4895.461499235082, 6239.407256310643, 6240.719167637467, 6257.936150854366, 6406.359364063603, 5196.402413722432, 5684.8010319638615, 5714.314282286069, 5728.971355143281, 5745.885082298372, 6094.293597792625, 6140.754369825157, 6141.0, 6142.232220972379, 6147.170132032208, 6047.12316714074, 6067.18953987826, 6119.082137679295, 6125.019996800491, 6132.202813634199, 6198.752049918043, 6251.749930002749, 6252.187215661935, 6258.9374106259265, 6262.248530176317, 6016.879662406727, 6157.692115394212, 6161.938380616137, 6170.753169873245, 6173.475254603409, 6111.956402836248, 6121.7474480264555, 6122.032243765608, 6127.785527506605, 6138.0, 4812.302216178635, 4827.444843843003, 4851.102546028996, 4889.535494128052, 4948.233023881304, 4254.680898932593, 4317.395564620892, 4336.457942757155, 4375.606195442426, 4439.866803995821, 5632.577556683265, 5640.210370548127, 5642.125470552071, 5643.435656434323, 5706.087026075891, 5953.7023148843255, 6064.545159112996, 6072.726727297282, 6091.482224011009, 6097.603655762361, 5597.888042239132, 5628.887422251614, 5631.662100273997, 5632.8028519002455, 5635.633683810539, 5296.25852380667, 5305.124654431976, 5329.227262046981, 5337.092694241988, 5341.946580534222, 6222.595531290452, 6249.0626406039455, 6260.217472815898, 6283.654399008007, 6294.842628934313, 6225.159603453476, 6254.21822272216, 6263.311035785969, 6273.341299163522, 6278.717457714505, 6311.431971122621, 6338.176037115146, 6356.459700925473, 6360.681965901779, 6363.809085727436, 6057.0, 6101.217066730222, 6144.324124346408, 6230.6884655767935, 6232.439080482778, 6104.216637118914, 6108.602940808833, 6113.480354169946, 6146.692665366803, 6221.720022598904, 6052.152698622109, 6070.939290607214, 6083.6045657032155, 6091.177691011657, 6116.143739876332, 6063.9696801517475, 6071.392860713893, 6081.787137450168, 6085.1785009023215, 6099.542534309856, 5749.0, 5825.2135961644635, 5848.327442344185, 5887.941120588739, 5913.58604897653, 6055.212822333068, 6101.206843110367, 6127.2034635498885, 6130.080487926846, 6133.018717005258, 5885.705714714161, 5890.705464726832, 5940.3168635605725, 5946.316173639913, 5951.375105613848], "value": 5665.156337395116, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.2285303485667, 537.1218058474393, 567.9521283232434, 594.3996563470893, 621.9892674404093, 29949.550756738667, 38058.23883522331, 39029.41455878164, 39243.0, 39320.80339598305, 41390.17219655612, 41727.79136104315, 41916.742497725114, 41936.37095443563, 42087.94780130498, 8461.95769021154, 9322.021187775288, 9547.331686781929, 9557.856632150488, 9558.187554057879, 8614.483131012159, 11409.20135590509, 11728.824067638992, 11732.706682332924, 11738.0, 11404.973552380285, 11498.137639677016, 11512.848715128504, 11513.366764827932, 11587.826182607268, 8522.659093636246, 11398.202125851194, 11423.828642570368, 11518.308901465896, 11564.363959982198, 11391.715207119805, 11547.422628868522, 11558.017568506688, 11568.305901645928, 11587.362695051768, 38728.419073713914, 39237.0, 39239.60760392388, 39250.41124383137, 39591.20817583661, 3106.5806116174253, 3303.7357011439026, 3469.6877281044576, 3503.8248087595775, 3534.293141371736, 28620.427591448264, 28782.424351513062, 28926.71073289261, 29369.559456608167, 29691.85154074216, 2646.6823981122197, 2731.576605626131, 2741.972580274192, 2750.4499100179846, 2778.4582006508535, 470300.0, 471046.93429598585, 472805.0, 473195.0, 473924.99999999657, 35498.46752298718, 63459.36540634627, 64032.0, 64497.67751161214, 64505.0, 36597.63402365996, 36801.0, 36814.8159259202, 36914.0, 36956.260874782354, 15261.9236903817, 15369.0, 15412.152331621644, 15454.459093931659, 15495.690086198212, 11844.940775296069, 12131.696707582381, 12617.369131543386, 12882.87432573615, 12888.646692097382, 8062.55655938929, 8083.797905052421, 8113.269805717396, 8169.877451838227, 8174.836503269901, 3374.3420033093803, 3440.174358154075, 3448.586169659657, 3448.77582957107, 3467.7225821934185, 983.8917719050899, 1061.3313612424124, 1085.934843909368, 1130.4912789244709, 1151.83874257604, 25443.10949116772, 29042.854785725936, 29071.12786616405, 29523.409531809673, 30088.69911300903, 1128.3963079752293, 1136.8067428537004, 1162.2561560601052, 1195.8086706126978, 1264.9367531623382, 15268.0, 15274.694506110038, 15336.0, 15654.765178522332, 15735.4492592761, 586795.0, 590945.0, 601662.0, 602671.0, 602853.0, 61759.0, 62371.376286237464, 62540.37459625345, 62545.0, 62673.05990410147, 1352.1481466675932, 1462.495439073518, 1490.9627259318386, 1504.2403586188905, 1513.0, 28993.85503072471, 29529.966451174107, 29568.704312956594, 30268.24329391783, 30291.545626815176, 49501.504984949686, 50308.99382012266, 50366.748166258934, 50603.73490662763, 50608.746956264986, 397654.0, 400931.0, 401530.0, 401707.0, 402704.0, 23306.184283549996, 26251.606225906246, 27235.0, 27442.862785686742, 27483.862580686968, 42830.71507854769, 43224.783876081674, 43278.350824738285, 43456.34815477707, 43590.56409435865, 35083.12292192614, 35465.11337216487, 35623.10942226465, 36349.63650363568, 36447.635523645484, 35166.296674066885, 35215.47176792299, 35569.82215088908, 35809.0, 37431.43852842155, 35471.0, 36162.8191859039, 36324.63675363318, 36531.086722832144, 37186.25627487489, 31646.525302120026, 32684.019479415656, 32889.67110328962, 32945.176370590925, 33362.33275334528, 23996.0, 24594.631080533447, 24621.50756984886, 24650.87674561616, 24660.076015282317], "value": 18679.246700476135, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.48661819924905, 379.77213671796915, 383.39232316777907, 388.4600405436442, 393.3234836081938, 14543.563693089225, 14972.0, 18061.006644634555, 19431.125599348044, 19445.708314375297, 14910.850891491109, 18446.538836529093, 19018.049097545136, 19087.331943381985, 20089.095990680416, 5657.43425657433, 5851.824445266649, 5901.0, 5903.4686878180955, 5903.966805808987, 4859.368282123337, 5387.568994480452, 5460.290162278899, 5787.160861675055, 5809.419058094199, 5648.943510564883, 5703.40114288, 5719.113537401709, 5754.827355179351, 5787.971060144693, 5571.777128914839, 5817.0692689169955, 5835.620684655484, 5840.707964601753, 5846.064629659234, 4869.4156701195725, 4999.677472175805, 5042.823501177478, 5073.6955782652785, 5082.212257100118, 24139.396515087086, 24217.878910605334, 24669.87665061681, 24708.50582988348, 24717.50564988708, 2157.9460513487134, 2183.8689678619335, 2229.1863469833556, 2483.639872218518, 2573.1894453247355, 14234.43062277518, 14655.853441465555, 14788.795284731925, 14797.852021479755, 14805.925970370186, 1700.4898530440819, 1702.1829521829468, 1766.0306382557553, 1767.8232176782346, 1932.4589115047809, 115604.84395156025, 117485.41257293745, 119168.0, 124394.0, 127354.0, 10836.67489975302, 10973.069446723037, 11088.223824332219, 11090.722731931604, 11098.55605775776, 13719.657008574866, 13762.0, 13780.862191377957, 13819.170849749047, 13863.4454621814, 7018.964905175441, 8233.0, 8438.45150065256, 8456.915430845613, 8531.701390451304, 8455.74632761018, 8508.10664880191, 8547.74356769298, 8600.655973761104, 8619.051904290523, 4229.133027729308, 4376.7811609419405, 4401.2517871961845, 4406.647468202536, 4407.625351845129, 1471.8380978092616, 1818.3908390689267, 1839.420582516503, 1844.7786265648208, 1866.3094654977745, 773.4199350487022, 815.8490679224362, 818.414833394126, 965.1747755668943, 1014.1937159957852, 17922.0, 18338.633227335646, 19632.803671963098, 20763.480912977295, 20940.0, 977.4868194198103, 1000.6791035832777, 1005.8843233028296, 1044.6865940217904, 1054.5254635414094, 8518.829623407497, 8805.251553618014, 10022.148117410103, 10383.376997380181, 10455.16358691303, 617624.0, 628178.0, 630885.0, 631463.0, 641885.0, 75625.86561201587, 78658.6067069661, 126136.0, 128491.0, 131570.34214829057, 998.5257002923698, 1001.5893483671659, 1037.870266216723, 1041.250299784154, 1055.0873494427265, 16660.916695416447, 17281.39515116965, 17640.294388224327, 17822.19800109024, 17985.820141798413, 51349.48650513447, 51417.228741569656, 52133.7393313031, 52296.738516307174, 52960.735196323774, 73384.89922651058, 76035.0, 77678.22321776937, 78848.0, 79213.60393197997, 7529.171791103084, 7586.6206689664195, 7618.123915749759, 7799.49303295284, 8010.198980101944, 9825.263105267277, 10473.266871318934, 11971.580994665144, 12002.879971200176, 12220.938895305822, 10574.0, 10620.893791062299, 10674.252802303765, 10803.513841876977, 10827.917208279108, 10298.3820970742, 10497.317674351143, 10519.0, 10519.631812886511, 10829.29609575375, 8668.869966950628, 10519.94740026295, 10629.521671524955, 10763.838542422027, 11058.668239952814, 7600.391968642385, 7712.267334603203, 7730.845383092419, 7781.844363112819, 8016.478928869605, 8748.256398206224, 10596.364218146937, 10680.092192163753, 10876.238663293494, 10883.346999180074], "value": 9262.37115081024, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.9496017639385, 1601.367459853358, 1601.8478244566766, 1874.2409324223695, 1998.530345368837, 7908.2487163719425, 8627.827443451128, 8791.64833406663, 8796.516191609475, 8798.428102173362, 8420.494770313786, 8655.783605409852, 8668.8699669505, 8671.393002489829, 8688.218060374566, 2087.8225350845196, 2130.968035479469, 2208.0284674743143, 2209.1163534586135, 2431.927042188728, 2204.559088182362, 2363.1374548289837, 2441.0357908626083, 2451.497443024176, 2455.1897873701723, 2390.0, 2403.194929698553, 2408.5423769483828, 2410.4693519615084, 2415.649730789043, 2109.24067335759, 2376.310869847742, 2389.629607410837, 2392.1986134644917, 2395.748446413136, 2345.835791494596, 2426.162973774045, 2435.111184417675, 2435.1111844176926, 2435.5494233566847, 7515.511491753018, 8076.030876294823, 8504.532250726208, 8590.14098590136, 8598.140185981414, 5088.516590923857, 5259.447757985395, 5266.131088370415, 5270.841874743764, 5281.551068159211, 3294.637589865113, 3385.9492107618407, 3450.4996775467416, 3467.49721290414, 3473.94789078164, 984.4437892590663, 996.3473924579412, 998.3710262534629, 1005.3615953869346, 1010.3079390617451, 11565.190436669436, 11573.189876708633, 11575.189736718348, 11580.884191158066, 11601.76796464066, 4490.191765482245, 4507.706999045052, 4720.5515476030405, 4738.005018945991, 4750.548697873696, 9763.853542196874, 9778.853317200248, 9784.510774461249, 9787.804243915081, 9807.509624518889, 5311.52196302327, 5314.654547454403, 5315.202719592092, 5352.357717073898, 5352.91970620441, 4793.520647935178, 4827.951720482821, 4844.491328410537, 4885.60915126789, 4887.926681099786, 3812.3328417526823, 3852.210296889131, 3897.4738410314812, 3918.294706952776, 3932.2528719543197, 1267.4930027988694, 1286.1832736212386, 1288.1176394170025, 1295.695511554784, 1297.072593095922, 859.6002858670623, 867.9262262707614, 871.7471933139445, 914.2584568907141, 917.9724608261763, 5183.255924499186, 5220.921591413485, 5238.869028274324, 5325.707086110301, 5333.013392522395, 379.6052105809982, 428.60836532150523, 432.9870103896888, 442.2724618003431, 446.00540794029496, 3654.8720794772057, 3797.411401232813, 3951.0, 4018.9397159042637, 4025.5571887092397, 10233.692989210336, 10763.623273185402, 10902.182336324808, 10904.07315378186, 10919.290246133976, 10562.683119506428, 10623.893761062445, 10850.945745271223, 10860.294080884874, 10904.236703430684, 8071.636776345079, 8182.18178182189, 8205.671773129128, 8222.62998165084, 8255.669773209125, 5612.298462692163, 5711.628744131618, 5758.942410576008, 5819.5344372450945, 5845.3277873045145, 9899.901000990187, 9988.0, 9990.400575965465, 10051.296409251283, 10093.495325233856, 7905.802354940943, 9260.629574817202, 9683.370580912218, 10295.794084118426, 10297.588096476056, 7271.054762880792, 7336.633168341668, 7437.70249190048, 7441.255874412515, 7448.553086814808, 7905.604719764104, 7947.761567152995, 8017.5991200438575, 8045.919540804517, 8145.714899978472, 7292.854142917218, 7390.03929489163, 7418.8145296365865, 7458.627068646654, 7495.250474952461, 6897.482688798461, 6937.202221744564, 6941.375276225164, 7089.397401220747, 7117.0, 6709.865802684017, 6715.160604924385, 6769.559978601375, 6770.119884414995, 6800.149981252343, 4584.88537786558, 4613.0, 4799.448063472745, 4879.878003049952, 4960.355153829979, 4804.039192161512, 4827.686200396963, 4853.0779151961015, 4866.732329721758, 4921.990991846591], "value": 4421.004273201, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.86497940905997, 307.8106964217007, 315.3267773303995, 315.83576540199095, 320.5944480232508, 5815.418458154182, 5858.2677165354335, 5882.705864706758, 5911.172435858984, 5926.940730592687, 5062.569681577071, 5416.295881535405, 5602.915956260659, 5844.26946631671, 5853.7951171709, 3675.8162091895365, 4264.8507302244425, 4350.8687741187205, 4471.686981911267, 4487.416635837353, 4189.958100418988, 4391.209582275189, 4439.889002774924, 4452.397137030666, 4470.150671372445, 3397.96602033979, 3998.4402183694306, 4090.0, 4434.979954610441, 4457.866264012068, 3890.338642430765, 4042.908414728022, 4057.2899742544937, 4105.486814148217, 4353.825846966118, 3799.601041890617, 3813.008617759375, 3820.3887378019385, 3832.8118283332255, 3856.5291237885845, 4384.693071484998, 5541.667499950016, 5565.833025009216, 5578.721063946787, 5586.972065139689, 4179.2059508693255, 4778.4982576829625, 4902.3872015998, 5044.066847633199, 5065.924011139836, 5328.387235467893, 5666.46168613981, 6081.847953801146, 6154.5384096192965, 6167.845803854894, 4206.747595144301, 4211.831526738927, 4224.366345048267, 4225.091605304843, 4229.957700423018, 4996.975015124937, 4998.600111991032, 5670.945274129018, 6465.74137034518, 6540.607563546202, 4122.660644877658, 4138.379243113557, 4141.275276826544, 4154.231467178582, 4167.874963751093, 4191.847242008411, 4227.534971153171, 4233.248388547408, 4245.80893859777, 4251.553586873396, 4393.406890069864, 4480.619147372445, 4673.509281525459, 4974.179260422062, 4980.726060066731, 5373.838784836461, 6191.81424557264, 6212.223472065992, 6252.96826023701, 6268.4671802897265, 1436.741386550431, 1455.5269537400388, 1456.6139972907188, 1459.4162335065846, 1469.1919444305588, 910.9726708198765, 951.5194826612517, 953.0517135450133, 963.8506031565119, 970.7573106723319, 5450.509454149145, 5695.373508914016, 5730.587447665274, 5746.482816546531, 5758.942410575924, 6318.747250109944, 6326.841828954313, 6334.651594162273, 6356.809295721136, 6360.554761166766, 5628.641139271972, 5672.432756724294, 5694.857628559236, 5733.757019805397, 5761.85595360111, 3277.311764529428, 3308.2721801203697, 3332.7667063305335, 3335.1328654549507, 3341.3818443588016, 6238.539444297258, 6562.639054851934, 6612.338766123349, 6612.867742645217, 6623.370779775913, 5396.055690254189, 5982.0, 6369.649669268235, 6430.196225471816, 6464.022702519034, 6207.81376558704, 6511.706973186218, 6539.346065393518, 6545.789029029645, 6591.6374599397495, 5206.427292997843, 6283.250825494804, 6298.1812364392335, 6299.464545513682, 6330.651814150266, 6174.25908890936, 6230.875382492234, 6232.657203853651, 6237.656928868956, 6239.719212635533, 5488.423715509974, 6036.788712395045, 6554.0, 6789.93210067893, 6797.796066118024, 3712.814359281971, 4163.500379954425, 4166.0, 4171.3325867860995, 4204.747715137101, 6145.569810113249, 6187.350328215653, 6444.226692796707, 6454.580452270588, 6455.419012288917, 5008.799648014038, 5969.134475501115, 6189.628622282497, 6279.246490421178, 6283.434490895841, 5164.638475306692, 5499.780008799763, 6219.968900155471, 6245.406686364788, 6254.374562543891, 5674.375818659862, 6088.756449741961, 6097.451229389376, 6106.572539922163, 6124.785632503019, 4667.8599642010795, 5938.317093534299, 5995.280566332068, 6219.067139929047, 6228.0, 5080.288759573737, 5767.250257466502, 5792.594518383673, 6084.513238940963, 6102.223488312896], "value": 4459.362718972304, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.5421660862661, 660.7324033766326, 661.9503537234707, 665.7802925034739, 667.3393340592813, 14815.33331000105, 15220.695586088299, 15244.0, 15251.847481525168, 15281.541553753392, 13937.0, 14550.345234464448, 14555.927220363883, 14556.417743290256, 14579.635509112277, 729.5112274775913, 746.7871656577877, 906.9932375063674, 913.2785099771173, 925.5557332480413, 884.6726711116863, 925.6112432778249, 927.2813569483636, 927.378656300277, 930.9898759845541, 964.6912987843892, 975.8731364922586, 976.9413835169912, 977.8679878216457, 979.5102448775613, 763.5380594740213, 918.8897332320098, 963.5712108111893, 965.5558443116175, 970.0832713086137, 719.0580339754886, 719.5538765965083, 719.5754504842116, 720.79097061852, 721.1346384338815, 15313.387464501406, 15396.384144634201, 15407.845921540753, 15439.536813895602, 15441.382344706199, 12278.938605307005, 12314.384280785926, 12373.0, 12499.000399840046, 12639.368031598475, 9880.505974701236, 10065.094141527225, 10379.377237365783, 10380.584776608926, 10807.83788243177, 3441.63862794408, 3496.5629296337956, 3588.3361578108133, 3607.9458808117897, 3684.797336146511, 83195.0, 84486.57756711177, 84953.0, 85169.72245416323, 86314.0, 9080.40977336471, 9257.629694812267, 9320.906790932004, 9407.623695052258, 9478.004809495042, 21066.946652667306, 21157.894210529157, 21177.835219062792, 21186.682199767016, 21201.0, 5793.536517078624, 6824.7952561423235, 6871.072405225331, 6884.793456196222, 6942.708906060988, 4066.694997875112, 4089.0595163112653, 4098.877033688994, 4153.688473364449, 4153.7507749535125, 3552.8401221945073, 3668.669819716185, 3672.137047793766, 3678.0988657779017, 3685.94471082934, 600.8708127752554, 619.1053927075333, 625.0249610607467, 649.1172006071739, 655.2366493035655, 512.743628185907, 566.7506297229206, 585.5783835638333, 594.6372712645283, 594.9702514874325, 7599.164091949881, 7658.195889431529, 7884.881726774103, 7897.763067107996, 8183.304419124322, 542.5361316074778, 563.9989019490355, 578.2557937355673, 594.3462191589217, 603.3513972479602, 3651.087228192952, 3719.9070023249637, 3787.9242415152094, 3960.3663413853646, 3971.3050216212623, 80717.0, 81995.5900220507, 82026.58986705147, 83140.18018738751, 83335.583322083, 67809.0, 68203.0, 68600.3139968604, 68954.9656755149, 68984.65507672429, 58290.708546457, 58335.124973125436, 59130.70434647799, 59419.108713369336, 59704.70147649234, 7290.781276561712, 7323.9267607323245, 7506.1743208248145, 7518.586477743776, 7531.962340188483, 41205.587944120176, 41222.58777412307, 41365.37951930663, 41676.58323416727, 43067.49263775878, 54435.0, 54967.725161373935, 56149.0, 58883.70558147182, 58965.41034589771, 9702.563384647567, 9785.07041831025, 9899.406035637596, 10204.693859184237, 10456.058954694114, 10837.458127093456, 10843.132549395872, 11130.833037504606, 11311.490982905954, 11597.304161750322, 9700.75748106303, 9777.364471309342, 10106.494675266355, 10158.238132140266, 10158.695239142837, 10245.436500992517, 10365.689029329133, 10604.469776511298, 10624.309419887682, 10664.520096595827, 9830.262730295399, 9852.458114803754, 9958.203343732628, 9973.351732137387, 10032.197424206191, 8741.34439916996, 8798.208161265517, 8929.330300227379, 8941.910580894108, 9014.143656352637, 8378.03652579961, 8513.233808957224, 8528.658853645784, 8628.0, 8754.430961987451], "value": 6570.477132361419, "unit": "score"}], "name": "dromaeo_css", "value": 7089.585424972808, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 112.0, 112.0, 112.0, 114.0, 118.0, 112.0, 114.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 141.0, 144.0, 169.0, 140.0, 147.0, 164.0, 109.0, 195.0, 117.0], "value": 148.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 189.0, 182.0, 186.0, 183.0, 187.0, 184.0, 185.0, 186.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 71.0, 85.0, 90.0, 46.0, 50.0, 75.0, 110.0, 90.0, 74.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 133.0, 77.0, 77.0, 79.0, 128.0, 79.0, 77.0, 124.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 92.0, 89.0, 91.0, 87.0, 93.0, 129.0, 91.0, 88.0], "value": 93.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 97.0, 96.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 112.0, 139.0, 84.0, 83.0, 86.0, 84.0, 86.0, 85.0], "value": 92.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 58.0, 57.0, 59.0, 58.0, 57.0, 77.0, 60.0, 58.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 72.0, 76.0, 72.0, 66.0, 67.0, 67.0, 69.0, 67.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 82.0, 82.0, 80.0, 80.0, 94.0, 106.0, 104.0, 82.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 138.0, 139.0, 147.0, 156.0, 140.0, 138.0, 184.0, 155.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 170.0, 181.0, 168.0, 169.0, 170.0, 168.0, 168.0, 169.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 64.0, 79.0, 93.0, 91.0, 75.0, 70.0, 67.0, 66.0], "value": 74.2, "unit": "score"}], "name": "kraken", "value": 1509.8, "alertThreshold": 2.0}]} 05:37:48 INFO - Return code: 0 05:37:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:37:48 ERROR - # TBPL SUCCESS # 05:37:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:37:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:37:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:37:48 INFO - 2016-04-16 05:37:48.303 screenresolution[1687:9872] starting screenresolution argv=screenresolution get 05:37:48 INFO - 2016-04-16 05:37:48.305 screenresolution[1687:9872] Display 0: 1600x1200x32@60 05:37:48 INFO - 2016-04-16 05:37:48.311 screenresolution[1688:9875] starting screenresolution argv=screenresolution list 05:37:48 INFO - Available Modes on Display 0 05:37:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:37:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:37:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:37:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:37:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:37:48 INFO - Intel Iris: 05:37:48 INFO - Chipset Model: Intel Iris 05:37:48 INFO - Type: GPU 05:37:48 INFO - Bus: Built-In 05:37:48 INFO - VRAM (Dynamic, Max): 1536 MB 05:37:48 INFO - Vendor: Intel (0x8086) 05:37:48 INFO - Device ID: 0x0a2e 05:37:48 INFO - Revision ID: 0x0009 05:37:48 INFO - Displays: 05:37:48 INFO - Display: 05:37:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:37:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:37:48 INFO - Display Serial Number: 1600x1200 60 05:37:48 INFO - Main Display: Yes 05:37:48 INFO - Mirror: Off 05:37:48 INFO - Online: Yes 05:37:48 INFO - Rotation: Supported 05:37:48 INFO - Return code: 0 05:37:48 INFO - Running post-action listener: _resource_record_post_action 05:37:48 INFO - Running post-run listener: _resource_record_post_run 05:37:49 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32001536; Write bytes: 696936448; Read time: 323; Write time: 2297 05:37:49 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 373273088; Write bytes: 368745984; Read time: 28360; Write time: 776 05:37:49 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31067648; Write bytes: 298854912; Read time: 309; Write time: 1466 05:37:49 INFO - Running post-run listener: _upload_blobber_files 05:37:49 INFO - Blob upload gear active. 05:37:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:37:49 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:37:49 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:37:49 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:37:50 INFO - (blobuploader) - INFO - Open directory for files ... 05:37:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:37:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:37:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:50 INFO - (blobuploader) - INFO - Done attempting. 05:37:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:37:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:37:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:51 INFO - (blobuploader) - INFO - Done attempting. 05:37:51 INFO - (blobuploader) - INFO - Iteration through files over. 05:37:51 INFO - Return code: 0 05:37:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:37:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:37:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d56def7948765d8184dee4262257207e9c6c918b695a6aec4ee73f5ace39b7b38fef0b3f1d38c48705f77c13ea7470e684089a9cd7ded13ef5922e34431daa2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb7b5253b50a7845de564b46b9bf0dcc0c489b321c17360255a195565eb66f1df9c5e9c9bc24c0a6d67cad0b31107cdc7353a69716bfd059217501e9ef551af5"} 05:37:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:37:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:37:51 INFO - Contents: 05:37:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d56def7948765d8184dee4262257207e9c6c918b695a6aec4ee73f5ace39b7b38fef0b3f1d38c48705f77c13ea7470e684089a9cd7ded13ef5922e34431daa2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb7b5253b50a7845de564b46b9bf0dcc0c489b321c17360255a195565eb66f1df9c5e9c9bc24c0a6d67cad0b31107cdc7353a69716bfd059217501e9ef551af5"} 05:37:51 INFO - Running post-run listener: copy_logs_to_upload_dir 05:37:51 INFO - Copying logs to upload dir... 05:37:51 INFO - mkdir: /builds/slave/test/build/upload/logs 05:37:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1349.263737 ========= master_lag: 0.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 29 secs) (at 2016-04-16 05:37:52.260471) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-16 05:37:52.265052) ========= 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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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/d56def7948765d8184dee4262257207e9c6c918b695a6aec4ee73f5ace39b7b38fef0b3f1d38c48705f77c13ea7470e684089a9cd7ded13ef5922e34431daa2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb7b5253b50a7845de564b46b9bf0dcc0c489b321c17360255a195565eb66f1df9c5e9c9bc24c0a6d67cad0b31107cdc7353a69716bfd059217501e9ef551af5"} build_url:https://queue.taskcluster.net/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011018 build_url: 'https://queue.taskcluster.net/v1/task/TfCOoFOgTzSlXUeYCL-Whw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d56def7948765d8184dee4262257207e9c6c918b695a6aec4ee73f5ace39b7b38fef0b3f1d38c48705f77c13ea7470e684089a9cd7ded13ef5922e34431daa2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb7b5253b50a7845de564b46b9bf0dcc0c489b321c17360255a195565eb66f1df9c5e9c9bc24c0a6d67cad0b31107cdc7353a69716bfd059217501e9ef551af5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-16 05:37:52.300200) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 05:37:52.300565) ========= 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.2IlOL4jQ2E/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.UOjbPiaZjN/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.004904 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-16 05:37:52.349694) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-16 05:37:52.350040) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-16 05:37:52.350455) ========= ========= Total master_lag: 0.66 =========