builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0111 starttime: 1463652196.15 results: success (0) buildid: 20160519004038 builduid: 60029db4a31e478bb3de011137e28f13 revision: c41ee7132426780f38bbffc58435548d92d2b327 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:16.149435) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:16.149882) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:16.150149) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:16.169857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:16.170172) ========= 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.ZL10j1asaX/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.aWVe9s1u6G/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-19 03:03:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.5M=0.001s 2016-05-19 03:03:16 (10.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.169207 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:16.357100) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:16.357405) ========= 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.ZL10j1asaX/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.aWVe9s1u6G/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.026600 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:16.405553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 03:03:16.405908) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c41ee7132426780f38bbffc58435548d92d2b327 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c41ee7132426780f38bbffc58435548d92d2b327 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZL10j1asaX/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.aWVe9s1u6G/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-19 03:03:16,473 truncating revision to first 12 chars 2016-05-19 03:03:16,473 Setting DEBUG logging. 2016-05-19 03:03:16,473 attempt 1/10 2016-05-19 03:03:16,473 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c41ee7132426?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 03:03:17,841 unpacking tar archive at: mozilla-aurora-c41ee7132426/testing/mozharness/ program finished with exit code 0 elapsedTime=1.617540 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 03:03:18.040310) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:18.040623) ========= script_repo_revision: c41ee7132426780f38bbffc58435548d92d2b327 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:18.041028) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:18.041289) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:18.054967) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-19 03:03:18.055261) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZL10j1asaX/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.aWVe9s1u6G/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 03:03:18 INFO - MultiFileLogger online at 20160519 03:03:18 in /builds/slave/test 03:03:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:03:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:03:18 INFO - {'append_to_log': False, 03:03:18 INFO - 'base_work_dir': '/builds/slave/test', 03:03:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:03:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:03:18 INFO - 'branch': 'Mozilla-Aurora', 03:03:18 INFO - 'buildbot_json_path': 'buildprops.json', 03:03:18 INFO - 'config_files': ('talos/mac_config.py',), 03:03:18 INFO - 'default_actions': ('clobber', 03:03:18 INFO - 'read-buildbot-config', 03:03:18 INFO - 'download-and-extract', 03:03:18 INFO - 'populate-webroot', 03:03:18 INFO - 'create-virtualenv', 03:03:18 INFO - 'install', 03:03:18 INFO - 'run-tests'), 03:03:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:03:18 INFO - 'download_minidump_stackwalk': True, 03:03:18 INFO - 'download_symbols': 'ondemand', 03:03:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:03:18 INFO - 'tooltool.py': '/tools/tooltool.py', 03:03:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:03:18 INFO - '/tools/misc-python/virtualenv.py')}, 03:03:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:03:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:03:18 INFO - 'installer_path': 'installer.exe', 03:03:18 INFO - 'log_level': 'info', 03:03:18 INFO - 'log_name': 'talos', 03:03:18 INFO - 'log_to_console': True, 03:03:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:03:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:03:18 INFO - 'opt_config_files': (), 03:03:18 INFO - 'pip_index': False, 03:03:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:03:18 INFO - 'cmd': ('bash', 03:03:18 INFO - '-c', 03:03:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:03:18 INFO - 'enabled': True, 03:03:18 INFO - 'halt_on_failure': False, 03:03:18 INFO - 'name': 'check_screen_resolution'},), 03:03:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:03:18 INFO - 'cmd': ('bash', 03:03:18 INFO - '-c', 03:03:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:03:18 INFO - 'enabled': True, 03:03:18 INFO - 'halt_on_failure': False, 03:03:18 INFO - 'name': 'check_screen_resolution'},), 03:03:18 INFO - 'run_cmd_checks_enabled': True, 03:03:18 INFO - 'sps_profile': False, 03:03:18 INFO - 'sps_profile_interval': 0, 03:03:18 INFO - 'suite': 'dromaeojs', 03:03:18 INFO - 'system_bits': '32', 03:03:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:03:18 INFO - 'title': 't-yosemite-r7-0111', 03:03:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:03:18 INFO - 'use_talos_json': True, 03:03:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:03:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:03:18 INFO - 'work_dir': 'build'} 03:03:18 INFO - ##### 03:03:18 INFO - ##### Running clobber step. 03:03:18 INFO - ##### 03:03:18 INFO - Running pre-action listener: _resource_record_pre_action 03:03:18 INFO - Running main action method: clobber 03:03:18 INFO - rmtree: /builds/slave/test/build 03:03:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:03:20 INFO - Running post-action listener: _resource_record_post_action 03:03:20 INFO - ##### 03:03:20 INFO - ##### Running read-buildbot-config step. 03:03:20 INFO - ##### 03:03:20 INFO - Running pre-action listener: _resource_record_pre_action 03:03:20 INFO - Running main action method: read_buildbot_config 03:03:20 INFO - Using buildbot properties: 03:03:20 INFO - { 03:03:20 INFO - "project": "", 03:03:20 INFO - "product": "firefox", 03:03:20 INFO - "script_repo_revision": "production", 03:03:20 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:03:20 INFO - "repository": "", 03:03:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:03:20 INFO - "buildid": "20160519004038", 03:03:20 INFO - "stage_platform": "macosx64", 03:03:20 INFO - "basedir": "/builds/slave/test", 03:03:20 INFO - "nightly_build": "True", 03:03:20 INFO - "buildnumber": 346, 03:03:20 INFO - "slavename": "t-yosemite-r7-0111", 03:03:20 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:03:20 INFO - "platform": "yosemite_r7", 03:03:20 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:03:20 INFO - "branch": "mozilla-aurora", 03:03:20 INFO - "repo_path": "releases/mozilla-aurora", 03:03:20 INFO - "revision": "c41ee7132426780f38bbffc58435548d92d2b327", 03:03:20 INFO - "pgo_build": "False", 03:03:20 INFO - "builduid": "60029db4a31e478bb3de011137e28f13", 03:03:20 INFO - "slavebuilddir": "test" 03:03:20 INFO - } 03:03:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:03:20 INFO - Running post-action listener: _resource_record_post_action 03:03:20 INFO - ##### 03:03:20 INFO - ##### Running download-and-extract step. 03:03:20 INFO - ##### 03:03:20 INFO - Running pre-action listener: _resource_record_pre_action 03:03:20 INFO - Running main action method: download_and_extract 03:03:20 INFO - mkdir: /builds/slave/test/build/tests 03:03:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:20 INFO - https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:03:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:03:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:03:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:03:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:03:21 INFO - Downloaded 1199 bytes. 03:03:21 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:03:21 INFO - Using the following test package requirements: 03:03:21 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:03:21 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:21 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:03:21 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:21 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:03:21 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:21 INFO - u'jsshell-mac.zip'], 03:03:21 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:21 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:03:21 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:03:21 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:21 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:03:21 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:21 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:03:21 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:21 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:03:21 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:21 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:03:21 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:03:21 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')]} 03:03:21 INFO - https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:03:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:03:22 INFO - Downloaded 18102566 bytes. 03:03:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:03:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:03:25 INFO - Return code: 0 03:03:25 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:25 INFO - https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:03:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:03:29 INFO - Downloaded 18102566 bytes. 03:03:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:03:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:03:33 INFO - Return code: 0 03:03:33 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')]} 03:03:33 INFO - https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:03:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:03:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:03:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:03:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:03:34 INFO - Downloaded 10840145 bytes. 03:03:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:03:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:03:34 INFO - Return code: 0 03:03:34 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')]} 03:03:34 INFO - https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:03:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:03:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:03:37 INFO - Downloaded 114262454 bytes. 03:03:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:37 INFO - mkdir: /builds/slave/test/properties 03:03:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:03:37 INFO - Writing to file /builds/slave/test/properties/build_url 03:03:37 INFO - Contents: 03:03:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:38 INFO - Running post-action listener: _resource_record_post_action 03:03:38 INFO - Running post-action listener: set_extra_try_arguments 03:03:38 INFO - ##### 03:03:38 INFO - ##### Running populate-webroot step. 03:03:38 INFO - ##### 03:03:38 INFO - Running pre-action listener: _resource_record_pre_action 03:03:38 INFO - Running main action method: populate_webroot 03:03:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:03:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:03:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:03:38 INFO - '--tppagecycles', 03:03:38 INFO - '7'], 03:03:38 INFO - 'tests': ['tsvgm']}}, 03:03:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:03:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:03:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:03:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:03:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:03:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:03:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:38 INFO - 'talos_options': ['--disable-e10s'], 03:03:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:03:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:03:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:38 INFO - 'talos_options': ['--disable-e10s'], 03:03:38 INFO - 'tests': ['damp', 'tps']}, 03:03:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:38 INFO - 'tests': ['damp', 'tps']}, 03:03:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:03:38 INFO - 'tests': ['dromaeo_dom']}, 03:03:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:03:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:03:38 INFO - 'tests': ['a11yr', 03:03:38 INFO - 'ts_paint', 03:03:38 INFO - 'tpaint', 03:03:38 INFO - 'sessionrestore', 03:03:38 INFO - 'sessionrestore_no_auto_restore', 03:03:38 INFO - 'tabpaint']}, 03:03:38 INFO - 'other-e10s': {'tests': ['a11yr', 03:03:38 INFO - 'ts_paint', 03:03:38 INFO - 'tpaint', 03:03:38 INFO - 'sessionrestore', 03:03:38 INFO - 'sessionrestore_no_auto_restore', 03:03:38 INFO - 'tabpaint']}, 03:03:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:03:38 INFO - 'ts_paint', 03:03:38 INFO - 'tpaint', 03:03:38 INFO - 'sessionrestore', 03:03:38 INFO - 'sessionrestore_no_auto_restore', 03:03:38 INFO - 'tabpaint']}, 03:03:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:03:38 INFO - 'ts_paint', 03:03:38 INFO - 'tpaint', 03:03:38 INFO - 'sessionrestore', 03:03:38 INFO - 'sessionrestore_no_auto_restore', 03:03:38 INFO - 'tabpaint']}, 03:03:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:03:38 INFO - 'tests': ['a11yr', 03:03:38 INFO - 'ts_paint', 03:03:38 INFO - 'tpaint', 03:03:38 INFO - 'sessionrestore', 03:03:38 INFO - 'sessionrestore_no_auto_restore', 03:03:38 INFO - 'tabpaint']}, 03:03:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:03:38 INFO - 'tests': ['a11yr', 03:03:38 INFO - 'ts_paint', 03:03:38 INFO - 'tpaint', 03:03:38 INFO - 'sessionrestore', 03:03:38 INFO - 'sessionrestore_no_auto_restore', 03:03:38 INFO - 'tabpaint']}, 03:03:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:03:38 INFO - 'tests': ['tsvgx', 03:03:38 INFO - 'tsvgr_opacity', 03:03:38 INFO - 'tart', 03:03:38 INFO - 'tscrollx', 03:03:38 INFO - 'cart']}, 03:03:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:03:38 INFO - 'tsvgr_opacity', 03:03:38 INFO - 'tart', 03:03:38 INFO - 'tscrollx', 03:03:38 INFO - 'cart']}, 03:03:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:38 INFO - 'talos_options': ['--disable-e10s'], 03:03:38 INFO - 'tests': ['tp5o']}, 03:03:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:38 INFO - 'tests': ['tp5o']}, 03:03:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:38 INFO - 'talos_options': ['--disable-e10s', 03:03:38 INFO - '--xperf_path', 03:03:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:03:38 INFO - 'tests': ['tp5n']}, 03:03:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:38 INFO - 'talos_options': ['--xperf_path', 03:03:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:03:38 INFO - 'tests': ['tp5n']}}, 03:03:38 INFO - 'talos.zip': {'path': '', 03:03:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:03:38 INFO - Running post-action listener: _resource_record_post_action 03:03:38 INFO - ##### 03:03:38 INFO - ##### Running create-virtualenv step. 03:03:38 INFO - ##### 03:03:38 INFO - Running pre-action listener: _resource_record_pre_action 03:03:38 INFO - Running main action method: create_virtualenv 03:03:38 INFO - Creating virtualenv /builds/slave/test/build/venv 03:03:38 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 03:03:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:03:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:03:38 INFO - Using real prefix '/tools/python27' 03:03:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:03:39 INFO - Installing distribute.............................................................................................................................................................................................done. 03:03:42 INFO - Installing pip.................done. 03:03:42 INFO - Return code: 0 03:03:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:42 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 0x10b44ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b45c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97d2ca1890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ebe00>, '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 0x10b154db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e6b20>, '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.ZL10j1asaX/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.aWVe9s1u6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 03:03:42 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 03:03:42 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 03:03:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZL10j1asaX/Render', 03:03:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:42 INFO - 'HOME': '/Users/cltbld', 03:03:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:42 INFO - 'LOGNAME': 'cltbld', 03:03:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:42 INFO - 'MOZ_NO_REMOTE': '1', 03:03:42 INFO - 'NO_EM_RESTART': '1', 03:03:42 INFO - 'PAGER': '/bin/cat', 03:03:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:42 INFO - 'PWD': '/builds/slave/test', 03:03:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:42 INFO - 'SHELL': '/bin/bash', 03:03:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWVe9s1u6G/Listeners', 03:03:42 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:03:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:42 INFO - 'USER': 'cltbld', 03:03:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:42 INFO - 'XPC_FLAGS': '0x0', 03:03:42 INFO - 'XPC_SERVICE_NAME': '0', 03:03:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:42 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 03:03:42 INFO - Cleaning up... 03:03:42 INFO - Return code: 0 03:03:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:42 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 0x10b44ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b45c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97d2ca1890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ebe00>, '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 0x10b154db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e6b20>, '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.ZL10j1asaX/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.aWVe9s1u6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 03:03:42 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 03:03:42 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 03:03:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZL10j1asaX/Render', 03:03:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:42 INFO - 'HOME': '/Users/cltbld', 03:03:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:42 INFO - 'LOGNAME': 'cltbld', 03:03:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:42 INFO - 'MOZ_NO_REMOTE': '1', 03:03:42 INFO - 'NO_EM_RESTART': '1', 03:03:42 INFO - 'PAGER': '/bin/cat', 03:03:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:42 INFO - 'PWD': '/builds/slave/test', 03:03:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:42 INFO - 'SHELL': '/bin/bash', 03:03:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWVe9s1u6G/Listeners', 03:03:42 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:03:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:42 INFO - 'USER': 'cltbld', 03:03:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:42 INFO - 'XPC_FLAGS': '0x0', 03:03:42 INFO - 'XPC_SERVICE_NAME': '0', 03:03:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:42 INFO - Downloading/unpacking psutil>=0.7.1 03:03:42 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 03:03:42 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 03:03:42 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 03:03:42 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 03:03:42 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 03:03:42 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 03:03:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:03:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:03:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:03:45 INFO - Installing collected packages: psutil 03:03:45 INFO - Running setup.py install for psutil 03:03:45 INFO - building 'psutil._psutil_osx' extension 03:03:45 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 03:03:46 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 03:03:46 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 03:03:46 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 03:03:46 INFO - building 'psutil._psutil_posix' extension 03:03:46 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 03:03:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:03:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:03:46 INFO - ^ 03:03:46 INFO - 1 warning generated. 03:03:46 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 03:03:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:03:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:03:46 INFO - Successfully installed psutil 03:03:46 INFO - Cleaning up... 03:03:46 INFO - Return code: 0 03:03:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:46 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 0x10b44ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b45c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97d2ca1890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ebe00>, '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 0x10b154db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e6b20>, '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.ZL10j1asaX/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.aWVe9s1u6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 03:03:46 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 03:03:46 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 03:03:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZL10j1asaX/Render', 03:03:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:46 INFO - 'HOME': '/Users/cltbld', 03:03:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:46 INFO - 'LOGNAME': 'cltbld', 03:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:46 INFO - 'MOZ_NO_REMOTE': '1', 03:03:46 INFO - 'NO_EM_RESTART': '1', 03:03:46 INFO - 'PAGER': '/bin/cat', 03:03:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:46 INFO - 'PWD': '/builds/slave/test', 03:03:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:46 INFO - 'SHELL': '/bin/bash', 03:03:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWVe9s1u6G/Listeners', 03:03:46 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:03:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:46 INFO - 'USER': 'cltbld', 03:03:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:46 INFO - 'XPC_FLAGS': '0x0', 03:03:46 INFO - 'XPC_SERVICE_NAME': '0', 03:03:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:03:47 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 03:03:47 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 03:03:47 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 03:03:47 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 03:03:47 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 03:03:47 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 03:03:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:03:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:03:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:03:49 INFO - Installing collected packages: mozsystemmonitor 03:03:49 INFO - Running setup.py install for mozsystemmonitor 03:03:49 INFO - Successfully installed mozsystemmonitor 03:03:49 INFO - Cleaning up... 03:03:49 INFO - Return code: 0 03:03:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:03:49 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')]} 03:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:49 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')]} 03:03:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:49 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 0x10b44ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b45c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97d2ca1890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ebe00>, '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 0x10b154db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e6b20>, '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.ZL10j1asaX/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.aWVe9s1u6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 03:03:49 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 03:03:49 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 03:03:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZL10j1asaX/Render', 03:03:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:49 INFO - 'HOME': '/Users/cltbld', 03:03:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:49 INFO - 'LOGNAME': 'cltbld', 03:03:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:49 INFO - 'MOZ_NO_REMOTE': '1', 03:03:49 INFO - 'NO_EM_RESTART': '1', 03:03:49 INFO - 'PAGER': '/bin/cat', 03:03:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:49 INFO - 'PWD': '/builds/slave/test', 03:03:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:49 INFO - 'SHELL': '/bin/bash', 03:03:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWVe9s1u6G/Listeners', 03:03:49 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:03:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:49 INFO - 'USER': 'cltbld', 03:03:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:49 INFO - 'XPC_FLAGS': '0x0', 03:03:49 INFO - 'XPC_SERVICE_NAME': '0', 03:03:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:50 INFO - Downloading/unpacking blobuploader==1.2.4 03:03:50 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 03:03:50 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 03:03:50 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 03:03:50 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 03:03:50 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 03:03:50 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 03:03:52 INFO - Downloading blobuploader-1.2.4.tar.gz 03:03:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:03:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:03:52 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 03:03:52 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 03:03:52 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 03:03:52 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 03:03:52 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 03:03:52 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 03:03:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:03:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:03:53 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 03:03:53 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 03:03:53 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 03:03:53 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 03:03:53 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 03:03:53 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 03:03:53 INFO - Downloading docopt-0.6.1.tar.gz 03:03:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:03:53 INFO - Installing collected packages: blobuploader, requests, docopt 03:03:53 INFO - Running setup.py install for blobuploader 03:03:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:03:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:03:54 INFO - Running setup.py install for requests 03:03:54 INFO - Running setup.py install for docopt 03:03:54 INFO - Successfully installed blobuploader requests docopt 03:03:54 INFO - Cleaning up... 03:03:54 INFO - Return code: 0 03:03:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03: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')]} 03:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03: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')]} 03:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:54 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 0x10b44ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b45c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97d2ca1890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ebe00>, '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 0x10b154db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e6b20>, '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.ZL10j1asaX/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.aWVe9s1u6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 03:03:54 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 03:03:54 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 03:03:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZL10j1asaX/Render', 03:03:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:54 INFO - 'HOME': '/Users/cltbld', 03:03:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:54 INFO - 'LOGNAME': 'cltbld', 03:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:54 INFO - 'MOZ_NO_REMOTE': '1', 03:03:54 INFO - 'NO_EM_RESTART': '1', 03:03:54 INFO - 'PAGER': '/bin/cat', 03:03:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:54 INFO - 'PWD': '/builds/slave/test', 03:03:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:54 INFO - 'SHELL': '/bin/bash', 03:03:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWVe9s1u6G/Listeners', 03:03:54 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:03:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:54 INFO - 'USER': 'cltbld', 03:03:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:54 INFO - 'XPC_FLAGS': '0x0', 03:03:54 INFO - 'XPC_SERVICE_NAME': '0', 03:03:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:03:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-EaX9Rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:03:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-15Belu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:03:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-NG4IGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:03:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-UL_AAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:03:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ATTADg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:03:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-7FeN0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:03:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-lT026j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:03:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4lO29B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:03:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-qY0Tkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:03:55 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-xFXP2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:03:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-yYnsRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:03:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-kAiJ9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:03:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-1HDzX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:03:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_0yjfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-fX_FdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:03:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-d4Vezh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:03:56 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-TQwjW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:03:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:03:56 INFO - Running setup.py install for manifestparser 03:03:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:03:56 INFO - Running setup.py install for mozcrash 03:03:57 INFO - Running setup.py install for mozdebug 03:03:57 INFO - Running setup.py install for mozdevice 03:03:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:03:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:03:57 INFO - Running setup.py install for mozfile 03:03:57 INFO - Running setup.py install for mozhttpd 03:03:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:03:57 INFO - Running setup.py install for mozinfo 03:03:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:03:57 INFO - Running setup.py install for mozInstall 03:03:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:03:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:03:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:03:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:03:57 INFO - Running setup.py install for mozleak 03:03:58 INFO - Running setup.py install for mozlog 03:03:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:03:58 INFO - Running setup.py install for moznetwork 03:03:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:03:58 INFO - Running setup.py install for mozprocess 03:03:58 INFO - Running setup.py install for mozprofile 03:03:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:03:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:03:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:03:58 INFO - Running setup.py install for mozrunner 03:03:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:03:58 INFO - Running setup.py install for mozscreenshot 03:03:58 INFO - Running setup.py install for moztest 03:03:59 INFO - Running setup.py install for mozversion 03:03:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:03:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:03:59 INFO - Cleaning up... 03:03:59 INFO - Return code: 0 03:03:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:59 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')]} 03:03:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:59 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')]} 03:03:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:59 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 0x10b44ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b45c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97d2ca1890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ebe00>, '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 0x10b154db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e6b20>, '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.ZL10j1asaX/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.aWVe9s1u6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 03:03:59 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 03:03:59 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 03:03:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZL10j1asaX/Render', 03:03:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:59 INFO - 'HOME': '/Users/cltbld', 03:03:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:59 INFO - 'LOGNAME': 'cltbld', 03:03:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:59 INFO - 'MOZ_NO_REMOTE': '1', 03:03:59 INFO - 'NO_EM_RESTART': '1', 03:03:59 INFO - 'PAGER': '/bin/cat', 03:03:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:59 INFO - 'PWD': '/builds/slave/test', 03:03:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:59 INFO - 'SHELL': '/bin/bash', 03:03:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWVe9s1u6G/Listeners', 03:03:59 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:03:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:59 INFO - 'USER': 'cltbld', 03:03:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:59 INFO - 'XPC_FLAGS': '0x0', 03:03:59 INFO - 'XPC_SERVICE_NAME': '0', 03:03:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:03:59 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-yBaOll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:03:59 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)) 03:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:03:59 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-77CvSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:03:59 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)) 03:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:03:59 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-H_Lswu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:03:59 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)) 03:03:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:03:59 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-PYrAdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:04:00 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)) 03:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:04:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-eaE3oW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:04:00 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)) 03:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:04:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-pIfRf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:04:00 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)) 03:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:04:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-MgyeLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:04:00 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)) 03:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:04:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-chQdJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:04:00 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)) 03:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:04:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-x2Ctsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:04:00 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)) 03:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:04:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-fLby_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:04:00 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)) 03:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:04:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0U1asm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:04:00 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)) 03:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:04:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-YB5yQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:04:00 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)) 03:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:04:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4WKZsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:04:00 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)) 03:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:04:00 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-l8CaVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:04:01 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)) 03:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:04:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-CEh4Sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:04:01 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)) 03:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:04:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-6SOqtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:04:01 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)) 03:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:04:01 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-sMVSyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:04:01 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)) 03:04:01 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)) 03:04:01 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)) 03:04:01 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)) 03:04:01 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)) 03:04:01 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)) 03:04:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:03 INFO - Downloading blessings-1.6.tar.gz 03:04:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:04:03 INFO - Installing collected packages: blessings 03:04:03 INFO - Running setup.py install for blessings 03:04:04 INFO - Successfully installed blessings 03:04:04 INFO - Cleaning up... 03:04:04 INFO - Return code: 0 03:04:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:04:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:04:04 INFO - Reading from file tmpfile_stdout 03:04:04 INFO - Current package versions: 03:04:04 INFO - blessings == 1.6 03:04:04 INFO - blobuploader == 1.2.4 03:04:04 INFO - docopt == 0.6.1 03:04:04 INFO - manifestparser == 1.1 03:04:04 INFO - mozInstall == 1.12 03:04:04 INFO - mozcrash == 0.17 03:04:04 INFO - mozdebug == 0.1 03:04:04 INFO - mozdevice == 0.48 03:04:04 INFO - mozfile == 1.2 03:04:04 INFO - mozhttpd == 0.7 03:04:04 INFO - mozinfo == 0.9 03:04:04 INFO - mozleak == 0.1 03:04:04 INFO - mozlog == 3.1 03:04:04 INFO - moznetwork == 0.27 03:04:04 INFO - mozprocess == 0.22 03:04:04 INFO - mozprofile == 0.28 03:04:04 INFO - mozrunner == 6.11 03:04:04 INFO - mozscreenshot == 0.1 03:04:04 INFO - mozsystemmonitor == 0.0 03:04:04 INFO - moztest == 0.7 03:04:04 INFO - mozversion == 1.4 03:04:04 INFO - psutil == 3.1.1 03:04:04 INFO - requests == 1.2.3 03:04:04 INFO - wsgiref == 0.1.2 03:04:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:04 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 0x10b44ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b45c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97d2ca1890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ebe00>, '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 0x10b154db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e6b20>, '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.ZL10j1asaX/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.aWVe9s1u6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 03:04:04 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 03:04:04 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 03:04:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZL10j1asaX/Render', 03:04:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:04 INFO - 'HOME': '/Users/cltbld', 03:04:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:04 INFO - 'LOGNAME': 'cltbld', 03:04:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:04 INFO - 'MOZ_NO_REMOTE': '1', 03:04:04 INFO - 'NO_EM_RESTART': '1', 03:04:04 INFO - 'PAGER': '/bin/cat', 03:04:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:04 INFO - 'PWD': '/builds/slave/test', 03:04:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:04 INFO - 'SHELL': '/bin/bash', 03:04:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWVe9s1u6G/Listeners', 03:04:04 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:04:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:04 INFO - 'USER': 'cltbld', 03:04:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:04 INFO - 'XPC_FLAGS': '0x0', 03:04:04 INFO - 'XPC_SERVICE_NAME': '0', 03:04:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:04 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)) 03:04:04 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)) 03:04:04 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)) 03:04:04 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)) 03:04:04 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)) 03:04:04 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)) 03:04:04 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)) 03:04:04 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)) 03:04:04 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)) 03:04:04 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)) 03:04:04 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)) 03:04:04 INFO - Cleaning up... 03:04:04 INFO - Return code: 0 03:04:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b44ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b45c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97d2ca1890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ebe00>, '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 0x10b154db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e6b20>, '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.ZL10j1asaX/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.aWVe9s1u6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 03:04:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:04:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZL10j1asaX/Render', 03:04:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:04 INFO - 'HOME': '/Users/cltbld', 03:04:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:04 INFO - 'LOGNAME': 'cltbld', 03:04:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:04 INFO - 'MOZ_NO_REMOTE': '1', 03:04:04 INFO - 'NO_EM_RESTART': '1', 03:04:04 INFO - 'PAGER': '/bin/cat', 03:04:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:04 INFO - 'PWD': '/builds/slave/test', 03:04:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:04 INFO - 'SHELL': '/bin/bash', 03:04:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWVe9s1u6G/Listeners', 03:04:04 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:04:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:04 INFO - 'USER': 'cltbld', 03:04:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:04 INFO - 'XPC_FLAGS': '0x0', 03:04:04 INFO - 'XPC_SERVICE_NAME': '0', 03:04:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:04 INFO - Downloading/unpacking jsonschema 03:04:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:04:07 INFO - Downloading/unpacking functools32 (from jsonschema) 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04:07 INFO - Downloading functools32-3.2.3-2.tar.gz 03:04:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:04:07 INFO - warning: no files found matching '*.txt' 03:04:07 INFO - no previously-included directories found matching 'build' 03:04:07 INFO - no previously-included directories found matching 'dist' 03:04:07 INFO - no previously-included directories found matching '.git*' 03:04:07 INFO - Installing collected packages: jsonschema, functools32 03:04:07 INFO - Running setup.py install for functools32 03:04:08 INFO - warning: no files found matching '*.txt' 03:04:08 INFO - no previously-included directories found matching 'build' 03:04:08 INFO - no previously-included directories found matching 'dist' 03:04:08 INFO - no previously-included directories found matching '.git*' 03:04:08 INFO - Successfully installed jsonschema functools32 03:04:08 INFO - Cleaning up... 03:04:08 INFO - Return code: 0 03:04:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b44ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b45c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97d2ca1890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ebe00>, '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 0x10b154db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e6b20>, '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.ZL10j1asaX/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.aWVe9s1u6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 03:04:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:04:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:04:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZL10j1asaX/Render', 03:04:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:08 INFO - 'HOME': '/Users/cltbld', 03:04:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:08 INFO - 'LOGNAME': 'cltbld', 03:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:08 INFO - 'MOZ_NO_REMOTE': '1', 03:04:08 INFO - 'NO_EM_RESTART': '1', 03:04:08 INFO - 'PAGER': '/bin/cat', 03:04:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:08 INFO - 'PWD': '/builds/slave/test', 03:04:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:08 INFO - 'SHELL': '/bin/bash', 03:04:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWVe9s1u6G/Listeners', 03:04:08 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:04:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:08 INFO - 'USER': 'cltbld', 03:04:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:08 INFO - 'XPC_FLAGS': '0x0', 03:04:08 INFO - 'XPC_SERVICE_NAME': '0', 03:04:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:08 INFO - Downloading/unpacking flake8 03:04:08 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 03:04:08 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 03:04:08 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 03:04:08 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 03:04:08 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 03:04:08 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 03:04:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:04:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:04:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:04:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:04:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:04:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:04:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:04:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:04:12 INFO - no previously-included directories found matching 'docs/_build' 03:04:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:04:12 INFO - Running setup.py install for pep8 03:04:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:04:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:04:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:04:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:04:12 INFO - no previously-included directories found matching 'docs/_build' 03:04:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:04:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:04:12 INFO - Cleaning up... 03:04:12 INFO - Return code: 0 03:04:12 INFO - Running post-action listener: _resource_record_post_action 03:04:12 INFO - Running post-action listener: _start_resource_monitoring 03:04:12 INFO - Starting resource monitoring. 03:04:12 INFO - ##### 03:04:12 INFO - ##### Running install step. 03:04:12 INFO - ##### 03:04:12 INFO - Running pre-action listener: _resource_record_pre_action 03:04:12 INFO - Running main action method: install 03:04:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:04:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:04:12 INFO - Reading from file tmpfile_stdout 03:04:12 INFO - Detecting whether we're running mozinstall >=1.0... 03:04:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:04:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:04:12 INFO - Reading from file tmpfile_stdout 03:04:12 INFO - Output received: 03:04:12 INFO - Usage: mozinstall [options] installer 03:04:12 INFO - Options: 03:04:12 INFO - -h, --help show this help message and exit 03:04:12 INFO - -d DEST, --destination=DEST 03:04:12 INFO - Directory to install application into. [default: 03:04:12 INFO - "/builds/slave/test"] 03:04:12 INFO - --app=APP Application being installed. [default: firefox] 03:04:12 INFO - mkdir: /builds/slave/test/build/application 03:04:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:04:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:04:45 INFO - Reading from file tmpfile_stdout 03:04:45 INFO - Output received: 03:04:45 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:04:45 INFO - Running post-action listener: _resource_record_post_action 03:04:45 INFO - ##### 03:04:45 INFO - ##### Running run-tests step. 03:04:45 INFO - ##### 03:04:45 INFO - Running pre-action listener: _resource_record_pre_action 03:04:45 INFO - Running main action method: run_tests 03:04:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:04:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:04:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:04:45 INFO - 2016-05-19 03:04:45.797 screenresolution[1642:7690] starting screenresolution argv=screenresolution get 03:04:45 INFO - 2016-05-19 03:04:45.800 screenresolution[1642:7690] Display 0: 1600x1200x32@60 03:04:45 INFO - 2016-05-19 03:04:45.805 screenresolution[1643:7693] starting screenresolution argv=screenresolution list 03:04:45 INFO - Available Modes on Display 0 03:04:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:04:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:04:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:04:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:04:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:04:46 INFO - Intel Iris: 03:04:46 INFO - Chipset Model: Intel Iris 03:04:46 INFO - Type: GPU 03:04:46 INFO - Bus: Built-In 03:04:46 INFO - VRAM (Dynamic, Max): 1536 MB 03:04:46 INFO - Vendor: Intel (0x8086) 03:04:46 INFO - Device ID: 0x0a2e 03:04:46 INFO - Revision ID: 0x0009 03:04:46 INFO - Displays: 03:04:46 INFO - Display: 03:04:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:04:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:04:46 INFO - Display Serial Number: 1600x1200 60 03:04:46 INFO - Main Display: Yes 03:04:46 INFO - Mirror: Off 03:04:46 INFO - Online: Yes 03:04:46 INFO - Rotation: Supported 03:04:46 INFO - Return code: 0 03:04:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:04:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:04:46 INFO - Python 2.7.3 03:04:46 INFO - Return code: 0 03:04:46 INFO - grabbing minidump binary from tooltool 03:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:46 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 0x10b2ebe00>, '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 0x10b154db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:04:46 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 03:04:46 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 03:04:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:04:46 INFO - Return code: 0 03:04:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:04:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:04:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:04:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:04:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:04:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:04:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:04:47 INFO - Return code: 0 03:04:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 03:04:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --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 03:04:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZL10j1asaX/Render', 03:04:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:47 INFO - 'HOME': '/Users/cltbld', 03:04:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:47 INFO - 'LOGNAME': 'cltbld', 03:04:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:04:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:47 INFO - 'MOZ_NO_REMOTE': '1', 03:04:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:04:47 INFO - 'NO_EM_RESTART': '1', 03:04:47 INFO - 'PAGER': '/bin/cat', 03:04:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:47 INFO - 'PWD': '/builds/slave/test', 03:04:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:04:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:47 INFO - 'SHELL': '/bin/bash', 03:04:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWVe9s1u6G/Listeners', 03:04:47 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:04:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:47 INFO - 'USER': 'cltbld', 03:04:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:47 INFO - 'XPC_FLAGS': '0x0', 03:04:47 INFO - 'XPC_SERVICE_NAME': '0', 03:04:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 03:04:47 INFO - mozversion application_buildid: 20160519004038 03:04:47 INFO - mozversion application_changeset: c41ee7132426780f38bbffc58435548d92d2b327 03:04:47 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:04:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:04:47 INFO - mozversion application_name: Firefox 03:04:47 INFO - mozversion application_remotingname: firefox-dev 03:04:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:04:47 INFO - mozversion application_vendor: Mozilla 03:04:47 INFO - mozversion application_version: 48.0a2 03:04:47 INFO - mozversion platform_buildid: 20160519004038 03:04:47 INFO - mozversion platform_changeset: c41ee7132426780f38bbffc58435548d92d2b327 03:04:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:04:47 INFO - mozversion platform_version: 48.0a2 03:04:47 INFO - using testdate: 1463652287 03:04:47 INFO - actual date: 1463652287 03:04:47 INFO - starting webserver on 'localhost:49276' 03:04:47 INFO - SUITE-START | Running 2 tests 03:04:47 INFO - TEST-START | dromaeo_css 03:04:47 INFO - operating with platform_type : mac_ 03:04:47 INFO - Initialising browser for dromaeo_css test... 03:04:47 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpe6Mx_q/profile http://localhost:49276/getInfo.html) 03:04:50 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 03:04:50 INFO - PROCESS | 1661 | colorDepth:24 03:04:50 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 03:04:50 INFO - PROCESS | 1661 | __metrics 03:04:50 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:04:50 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:04:51 INFO - TEST-INFO | 1661: exit 0 03:04:51 INFO - Browser initialized. 03:04:51 INFO - Running cycle 1/1 for dromaeo_css test... 03:04:51 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpe6Mx_q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:05:02 INFO - PROCESS | 1664 | RSS: Main: 210972672 03:05:02 INFO - PROCESS | 1664 | 03:05:54 INFO - PROCESS | 1664 | 1463652354574 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] 03:08:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 03:08:06 INFO - PROCESS | 1664 | RSS: Main: 340111360 03:08:06 INFO - PROCESS | 1664 | 03:11:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 03:11:10 INFO - PROCESS | 1664 | RSS: Main: 359456768 03:11:10 INFO - PROCESS | 1664 | 03:14:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 03:14:13 INFO - PROCESS | 1664 | RSS: Main: 356941824 03:14:13 INFO - PROCESS | 1664 | 03:17:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 03:17:15 INFO - PROCESS | 1664 | RSS: Main: 362274816 03:17:15 INFO - PROCESS | 1664 | 03:20:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 03:20:19 INFO - PROCESS | 1664 | RSS: Main: 323379200 03:20:19 INFO - PROCESS | 1664 | 03:23:22 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 03:23:22 INFO - PROCESS | 1664 | RSS: Main: 366727168 03:23:22 INFO - PROCESS | 1664 | 03:23:22 INFO - PROCESS | 1664 | __start_tp_report 03:23:22 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 03:23:22 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 03:23:22 INFO - PROCESS | 1664 | |i|pagename|runs| 03:23:22 INFO - PROCESS | 1664 | |0;dojo.html;3386.2888793353395;3451.102713294541;3458.5676790401194;3473.270613171234;3519.102628829648;5608.719564021798;5634.63374880633;5663.801766938158;5673.120666296731;5690.943090569098;5648.124540696199;5688.118341657039;5695.658260504372;5700.315962084545;5701.486866182043;5059.2158215476475;5089.770960306795;5100.413452452978;5182.818601348954;5214.791408343662;5202.219667049935;5207.713575753332;5209.479052094798;5213.32226810516;5222.216667499867;5114.667546609477;5144.588432925357;5152.201408781645;5163.535281824635;5186.118359878829;5179.792808287645;5202.713850738208;5214.4524824893215;5218.817341393033;5259.368875734898;5021;5058.215976523645;5068.290439338495;5149;5153.845384638467;4623.884402889955;5499.340079190482;5522.66863988162;5544.695041772701;5545.334559852803;6215.782447614311;6280.15217945576;6281.309056003836;6285.685715714196;6285.685715714196;6072.060840294228;6222.595531290452;6283.214598175228;6310.779122730728;6338.0492926061;5742.08126699726;5759.395263497357;5763.3084029915835;5778.855528611818;5779.884402311972;6308.337624549448;6321.810345689545;6327.873442531123;6333.873322533524;6344.8413789654705;5051.722155281494;6011.549133814981;6082.361352058058;6095.634261944208;6175.3515880832765;6260.906086408708;6274.058891166362;6275.811725648238;6280.497560195174;6298.527610429236;6165.32181460039;6206.162168107247;6211.130441738161;6217.813465596039;6221.222347206599;6020.187274717945;6117.279373342116;6138.296437970319;6142.590862071594;6233.781817636361;4526.637868970474;4607.216773148572;4848.200046992278;4863.70817750929;4872.171730805771;4408.228560002051;4430.158269928703;4449.9555004450185;4467.285234362486;4481.462224533012;5677.261955945701;5694.715264236772;5706.629069110578;5714.742836572281;5716.456936591017;6110.755569777248;6187.752489900354;6205.193324867829;6258.311585725566;6307.779227727007;5669.518090962232;5678.205051292823;5684.34630017545;5689.7724091036725;5693.772249110072;4448.9110217796115;5209.973950130301;5374.973125134349;5382.192671099367;5390.245365648812;6230.657313847782;6237.376262373727;6308.905366419415;6348.587341822859;6356.555041147168;5091.745412729275;6250.906236406367;6293.37066293367;6304.936950630435;6315.021171718391;5317.361916570037;6288.5598008138995;6329.082283068838;6335.841603959938;6337.683115844282;6079.908801367895;6128.693565321627;6134.528431832502;6175.814725558241;6177.135201071764;5041.546260836542;5512.91730624033;6196.876062478816;6231.158793562811;6253.812385628256;5478.534324582455;5529.894021195857;5960.4635582797755;5991.760329586942;5994.820155395345;5871.823845284648;5958.19564358806;5985.341612422543;6006.669633170218;6061.121137435135;5868.23712917318;5870.706464676835;5872.1778950947755;5872.823815285548;5883.470487656132;5982.910256346246;5985.910211346746;5985.940140598538;5986.102084687332;6023.608465449732;5768.798092066925;5885.793997210077;5944.138099975566;5987.2815262168815;5988 03:23:22 INFO - PROCESS | 1664 | |1;ext.html;522.1280461629079;528.4741682026385;532.7709085093413;545.5172172627224;550.5650536076499;29948.85025574872;31646.367072658533;38092.809535952285;39286.21427571447;39421.40867886984;41458.963525911866;41980.37029444561;42057.579424205745;42058.15883682325;42090.947726306855;9468.905310946888;9698.515074246296;9699.36954097985;9719;9737.172340351044;8675.69635062773;8897.733068007938;11705.882941170607;11891.762164756743;11903.345316007617;11575.305481671085;11629.825552616718;11635.592754253603;11639.941800290986;11762.588309409173;8603.354748393833;11582.768344633103;11676.065914726843;11682.824757628643;11700.824487632692;11525.250858694159;11659.300441973508;11660.650180494515;11678.824817627743;11685.824712629317;38327;38813.80593097045;38850.61149388498;38972;39018.60981390207;3182.347618738153;3544.3620148373284;3612.530371051773;3667.559892812853;3706.5181526401666;27519.31201719973;28923.71076289231;29047.854760726274;29269;29285;2729.6178535005115;2750.752432281104;2760.061579063128;2779.999200287895;2780.374415756439;482751;485367;485602;486173;487472;62063.37936620576;62474;62479;62618;62645.74708505804;69934.65032674804;70936.93594596085;71099.64450177716;71159.64420177866;71288.93066603901;17688.380906668463;17800.821991779918;17956.820431795775;17961.281548737905;17987.460376188734;12572.182808117443;12798.680032999064;13537;13665;13830.23933683657;19423.046378653104;19979.500512487302;19990.20039198445;20042.89978550127;20089.895055772075;8367.288780453608;8442.662293508314;8488.278496327757;8508.702195423131;8520.233179013794;916.7753900294471;956.5886668732512;989.2284018465608;1008.7074748323051;1036.7097212780434;29668.851655742015;30655.386892262475;30903.690963090532;32740;33191.83404082964;1020.1277907389224;1090.0734375780523;1131.1685910855515;1138.1463902073444;1156.5894107591853;14783.556493305217;14846.18345990981;15005.849941500664;15055.54833355001;15163.848361516464;616239;624611.8769406215;625620;626455;627392;59951.400485994585;60637.393626064055;60655.393446064976;60665.393346066856;60761.08858367128;1296.8378952630922;1519.1037288000132;1530.051368151753;1537.9923100384426;1557.696249231389;28851.990180343586;29150.416991659626;29247.78274389858;29694.55458168086;29784.851075744482;52734.472655272955;53318.73450123903;53618.65953351197;53700.73149634227;53715.46284537105;462245.688771554;470837;471221;471465.0694211851;471786;23239.767602323762;26442.338941526617;26834;26837.731622684303;27195.456090877677;41429.79285103676;42307.94230144271;44261.55738442703;44700;45711;35394.64605353914;35758.8212058938;37047;37689.62310376971;37718.8114059428;30564.694353056184;36295.818520907225;36608;36645.63354366529;37477.875663730134;36485.817570911975;36675.633243667224;36750;36826.8158659205;37286;31065.37869242648;32630.34739305248;32636.510452342758;32638.51042234416;32675.509867351535;24486;24779.50440991206;24810.751892480843;25191;25261.368465788502 03:23:22 INFO - PROCESS | 1664 | |2;jquery.html;391.3679407756473;394.8578511735776;394.9842006319747;396.0415793779055;400.251529639574;19022.334218302363;19753;20484.89757551214;20508.89745551272;20765.688514672293;19881.20475180991;20445.97770111496;20730.792692073075;20748.585028299425;20755.896220518913;5378.650387724795;5708.514776244025;6000.249968753906;6070.635761854281;6081.726322315484;5961.016432288668;5972.283326000886;6024.608400453978;6025.517958563326;6027.246594175728;5751.884962300773;5963.85090372742;5964.821055368324;5967.283925928895;6084.1786358841655;5955.374685658008;5978.671173085479;6003.249593800775;6004.039353703431;6006.8498287542725;5691.9754444200025;5723.771049158028;5788.450097240755;5788.942110578882;5795.362510123883;13242.404091815892;13408.530701425403;13469.191848489121;13502.324883755773;20428;1872.3353209610527;1988.3040935672543;2081.0219196977396;2329.6389059695775;2755.931101722453;9570.760730981781;9947.502624868728;9958.601655933753;12986.480540778357;14002.929985350009;1689.0541296873666;1767.1517671517743;1780.563761878335;1827.954301142482;1885.0668918885103;114304.85695143025;115070.42464787707;115377;116566.41716791446;120034.7996520041;11292.830607540893;11456.3698996556;11478.94260528692;11569.247998880046;11697.883021169679;14544.563663090124;14594.124352538882;14624.85375146235;14641.414343426357;14647.26763661834;8595.914040859636;8762.693305734269;8769.561521924006;8849.20357167858;8907.55462226886;8884.111588841193;8897.466152030898;8910.307041662063;9022.278217742696;9026.097390260922;3762.6613604775703;3837.213371258885;4450.1767173073085;4474.597286244189;4581.152486789954;1805.5847155154388;1881.5014021284371;1928.257620815996;1941.9611607768047;1982.3359174676646;740.6629983357559;756.6595032235406;989.4755779436766;1021.2646894236138;1041.9892704077033;19653.410397687807;19992.90003549973;20064.996750162434;20166.294179703935;20954.685679714818;911.9908800912038;987.5704068729982;997.2420960070253;1015.8527013582989;1087.690008347617;10374.636887708893;10420.32267902584;10424.895751042544;10462.319949203278;10657.360558366368;487497;495611;495903;595595;626915;88686;101708.4572885669;105476.47261763642;123339;149709.50290497174;1037.8028174646945;1047.7433028907967;1050.1546255264586;1081.0594782539242;1082.1451053667672;14487.85512144865;17577.209025593624;17731.645367092842;17752.55618609545;18230.81769182291;36340.81829590835;52590;53704.46295536995;54727.452725472234;57772.555686108186;80020;82608;83589.58205208935;84004.73992889989;84255;7927;8042.155573664681;8154.75535733929;8214.548199849065;8223.876641850258;9102.726918192464;12164.330961797186;12491.562795302121;12617.242965422103;12666.746665066832;10557.524911378852;10909.890901090888;11135;11162.609308674157;11265.26775759573;10674.956215415143;10720.785584288427;10798.028177464066;10987.63765790359;11118.49966751482;11038.392888391216;11195.832062518906;11335.03652189573;11386.202965792316;11448.885511144781;7712.9614351927885;7951.3638908886;8095.797605059921;8173.141820108803;8386.077531471412;8282;10646.8402973957;10941.07000904932;11149.442527873736;11272.66182014541 03:23:22 INFO - PROCESS | 1664 | |3;mootools.html;1441.7837324401346;1553.9300731467085;1579.218286947961;1823.890566566006;1960.2844961589014;7938.84122317554;8614.396992210548;8800.515971621558;8815.118488151182;8817.426867253624;8467.872981905277;8718.171773681506;8721.43310684805;8723.65105395785;8726.389152759293;2426.599611064172;2458.6926634170727;2458.918076046543;2459.4712136890566;2460.458699086198;2403.939901502468;2455.45979884426;2467.3584867934287;2468.271859801363;2473.5547601431736;2428.2715185444385;2462.741412151725;2476.913308034219;2480.0203919451806;2482.466269752003;2423.345696661909;2460.3357093584723;2469.8147638927153;2470.789982851442;2472.7898128659085;2402.0992127952018;2437.7805997460314;2451.2156110044793;2452.497238066192;2461.1755062054235;8533.786655333604;8757.562121893943;8819.867701984474;8835.602397892111;8871.911280887172;5446.373667028302;5533.114701647757;5583.6928968906695;5596.692181929992;5600.019996500596;3535.6464353564693;3602.3155600435834;3653.9269214615824;3676.871309504181;3683.1528748388023;1024.6925922223304;1049.2864851900704;1056.4242487844067;1063.3566692151214;1073.5544748929185;11717.296962182296;11812.88187118135;11935.985441237504;11949.163558550817;11977.22148060382;4073.7555746655294;4098.4467096941535;4489.742871995846;4704.200285951397;4715.854286299726;9397.295202859812;9409.012053734396;9420.858687119699;9425.104615061558;9436.150746432717;5377.462253774591;5378.596605254622;5383.380911195184;5402.054640437907;5408.891822163535;3929.9017524562114;4435.334699795057;4858.125537403231;4988.276699878497;4994.1509943309;3837.1174629835295;3851.557070936878;3894.3963685628773;3934.567197608261;3938.980305098456;1197.4012993503247;1199.2744389644315;1203.48851738011;1219.091776626419;1219.16487206264;707.3315716647719;798.8362385710915;883.3595643158659;910.7267819654078;925.5834874306589;5464.508194262536;5740.339860916049;5788.102844059177;5810.244668193192;5907.32065812437;928.2110206324705;951.8477043672979;952.8284908716497;977.8191034658611;1007.496251874063;3620.71034317254;3691.630836916287;3844.038990252437;3912.139329347539;3984.820683069269;10550.841737373945;10731.89268107309;10739;10764.677059688065;10928.453577321103;10575.312604680672;10756.19328550362;10777.137828973664;10779.137668986463;10819.56721731122;6561.639109848908;8188.836223275501;8426.494410335277;8428.199321064489;8457.915420845837;5612.214289999321;5724.713764311685;5772.567057470623;5857.941420585739;5858.062709966385;9413;9465.621375144918;9765.511724413609;9767;9777.315587909063;8509.531975741145;9257.583408746488;9851.113399794052;9914.752131196778;10107.39355638664;7452;7538.849223015399;7705.30652241301;7718.729844455417;7720.7683769487;8212.46618969765;8219.383546233938;8241.876371854547;8247.298979586802;8293.875591866006;6079.939200608114;7037.577745335297;7194.964025179841;7424.1462231844025;7498.362639175732;7065.57606543609;7217.422606191387;7257.310555497219;7288.744893928687;7377.668004939898;6829.146356705412;6911.550749201286;6913.412359949461;6917.412019978157;6926.792196234121;4690.835820746258;4721.268203428474;4730.692504987165;4859.95140048595;4922.803087876445;4791.880202994814;4838.612910967185;4857.684250523705;4868.707877527359;5092.719900405513 03:23:22 INFO - PROCESS | 1664 | |4;prototype.html;315.1821024441574;321.0096851214005;324.18952618453875;328.2646871008941;332.41992722698893;6365.140706004688;6427.260865000527;6455.289918109004;6478.384553467425;6489.935100648992;6401.359864013596;6416.615003099806;6420.839479013027;6441.645709485976;6442.355764423567;4523.163214805255;4610.907781844361;4638.814447422099;4719.598834099089;4744.169770290203;4683.103343146031;4739.383880095583;4739.905201895977;4770.97614511927;4776;4694.882627934312;4697;4703.670743047988;4704.97685995022;4715.269133284346;4521.299198624219;4540.364348991127;4648.116857797041;4683.110209060302;4691.906161876744;3442.638522955104;3504.6144924058335;4044.2518134144984;4055.7363771355062;4056.006278461796;5899.498542623882;6172.59878107926;6177.81466556004;6210.627362358273;6245.7501699931945;5576.9163462548095;5579.944200558024;5593.664380137164;5603.719814009283;5605.775768969236;6805.217399999014;6821.931780682179;6833.897491537631;6836.4530837532875;6839.452843772487;4301.892452688677;4445.688801783876;4449.644028477714;4466.218411777926;4469.240229161018;6975.965120174366;7184.425245980309;7215.747448839318;7223.386012188917;7228.927710722878;4531.932021019687;4539.046800172001;4544.18204723153;4544.818207271672;4550.385697930803;4585.358049873021;4612.884677883013;4616.515265897099;4616.630669546428;4616.976915115403;5455.8363249102595;5457.426970168154;5458.9454105458435;5461.399246082928;5466.508014278734;6584.703688334036;6854.6229957352825;6860.862782744317;6875.1437111979485;6906.447484201251;1581.9604509887115;1587.1588058329105;1605.1733357320988;1608.4852847089053;1610.2673283655909;947.0860619502075;995.9253056020827;1000.6497725795913;1021.7037059252882;1022.7085280695115;6226.128342032119;6260.280067792171;6272.529560282997;6280;6284.088807123033;7146.499745017799;7169.5698258104685;7173.5337203081635;7181.605011724301;7184.066071410788;6447.806565803034;6464.903026454607;6468.191476065492;6471.2234531856475;6482.189726284215;3734.8506059757847;3779.3575092234387;3781.5084039074745;3782.1868298315994;3782.9432558511644;7103.82240443993;7432.777016689508;7443.4789564729945;7448.441366897504;7489.176190619028;6035.336113027563;7352.485326027234;7357.632118394166;7365.15300740411;7365.631718414058;6065.727042283108;7428.517146385468;7439.702411903463;7443;7450.286434120075;5813.3895940925595;7104.254053324326;7138.286171382821;7150.213476517683;7162.606056666933;6686.364795344434;7050.224475307815;7054;7064.152301723826;7064.505484616027;6089;7281.0534630499815;7408.555486670602;7545.849083018418;7653.3494652952895;3883.0292426893275;4173.269677806311;4531.43357080365;4584.335271385564;4584.77076146198;6794.864102717819;7087.114110736158;7094.006839042641;7097.645117744195;7098.467614929004;6560.114384558024;6854.417374523221;6855.931440685529;6865.313468653295;6901.827454313682;5273.683578985273;6811.9659401702675;6824.761133360308;6840.452763778985;6860.759873404407;6700.4639628830555;6709.060731497691;6709.396154346132;6712.362325579063;6713.227978782307;5586.357568879631;6869.175698916162;6872.518923675295;6872.965635171792;6891.655417229219;6449.258335291502;6484.092227088306;6602.537822352581;6615.338466153346;6725.49558783103 03:23:22 INFO - PROCESS | 1664 | |5;yui.html;635.5106567942685;672.949528785341;690.5442408010714;723.2947875821075;727.785303335516;14892.553223403289;15072.246387680627;15072.623184420396;15115.77326340103;15118.244087795621;13627.454901803942;13673.794893076612;13704.24626645534;13704.52034178804;13740.106893051945;708.376628566863;724.5507785173173;884.9911500885005;893.8972018217924;913.4199783137722;910.3400034974657;917.8439665256923;919.3610440743686;919.7240827751648;919.7562645898843;953.323140570196;974.8440249560072;975.2490582251662;975.7511834482198;976.4190306767462;752.255267285389;950.1970834644715;965.7778710896498;971.2715463402377;971.499677665999;714.3463730686416;715.588536591462;716.3087620446295;716.5987047253554;717.7272636398187;14666.706665866623;14784.926075369554;14787.186704731235;14795.260236988108;14795.852041479555;12575.811362829565;12740.426680799386;12909.741805163938;13043.673908152277;13099.934500327532;9986.679125991586;10018.649347272883;10106.545205465773;10229.897701022968;10236.129928956048;3445.776024558396;3454.136465883529;3507.9649203508147;3574.4995700601935;3575.606683264839;77829.83255251175;78227;78280;78344.60827695826;79880.40538175796;8097.433179677366;9615.51922403877;9744.90255097454;9750.75623109428;9826.65606703762;21735.23926662559;21811.345659630235;21813.67279490809;21834.454138646663;21847.672284915738;7146.03528523653;7204.387627051759;7280.9271907280245;7288.344049035612;7482.738104166328;3863.0535518798074;3958.4062390641056;4028.9597104028585;4038.818253178555;4185.6232939035635;3550.289942011608;3600.801955892399;3603.135247540624;3613.638636136365;3613.6567026132475;618.11300783376;648.8053583924898;650.2846868444767;651.2478087808589;656.6683824668512;524.4257537995888;554.9428339014185;571.8799052199001;577.2207519848255;607.5716619783042;7950.960245198737;8015.398845086642;8177;8211.671533138608;8232.423730338818;526.8129813916005;529.8383992882208;552.6297380754904;575.9164921086419;602.734796689464;3607.603163651996;3701.9259614808093;3749.1751814600743;3786.1670432504807;3961.564227934925;89968.5501572488;92677;92898.60652090223;93217.60173597402;93574;75569;78168;78942;79944.60027699824;81152.18847811564;67157.66421167862;67252.31869203309;67327.99008014884;68373.3162668367;69178.65410673014;7043.647817608997;8199.631016604148;8283.337333013465;8351.123132071045;8553.358498112791;48947;52192.69518262074;52385;53821.19268210902;54534.7273263647;66494.33505665074;70005.95415302092;74661;74775;75705;11268.323900565665;11282.266652667551;11410.543578256777;11560.710982225175;11845.703857403634;11810.468528916048;11965.581204657796;11997.820032699341;12110;12536;8840.734777956672;11081.27971681838;11319.773604528027;11507.309561426342;11970.22193557416;9983.051610097029;11223.831642525205;11395.544178232778;11525.308481491138;11599.420028998347;11087.556497740323;11144.498497567467;11231.15766317513;11588.536458541565;11760.35318057515;9696.660616878658;9807.670343094745;9834.360766550151;9894.257930655374;9896.752581185528;9721;9811.656592019244;9816.509174541101;9867.65463208813;9891.307608467338 03:23:22 INFO - PROCESS | 1664 | __end_tp_report 03:23:22 INFO - PROCESS | 1664 | __start_cc_report 03:23:22 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1224 03:23:22 INFO - PROCESS | 1664 | __end_cc_report 03:23:22 INFO - PROCESS | 1664 | __startTimestamp1463653402615__endTimestamp 03:23:22 INFO - PROCESS | 1664 | 03:23:22 INFO - PROCESS | 1664 | ------- Summary: start ------- 03:23:22 INFO - PROCESS | 1664 | Number of tests: 6 03:23:22 INFO - PROCESS | 1664 | 03:23:22 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5666.75 Median:5779.88 stddev:647.86 (11.2%) stddev-sans-first:624.66 03:23:22 INFO - PROCESS | 1664 | Values: 3386.3 3451.1 3458.6 3473.3 3519.1 5608.7 5634.6 5663.8 5673.1 5690.9 5648.1 5688.1 5695.7 5700.3 5701.5 5059.2 5089.8 5100.4 5182.8 5214.8 5202.2 5207.7 5209.5 5213.3 5222.2 5114.7 5144.6 5152.2 5163.5 5186.1 5179.8 5202.7 5214.5 5218.8 5259.4 5021.0 5058.2 5068.3 5149.0 5153.8 4623.9 5499.3 5522.7 5544.7 5545.3 6215.8 6280.2 6281.3 6285.7 6285.7 6072.1 6222.6 6283.2 6310.8 6338.0 5742.1 5759.4 5763.3 5778.9 5779.9 6308.3 6321.8 6327.9 6333.9 6344.8 5051.7 6011.5 6082.4 6095.6 6175.4 6260.9 6274.1 6275.8 6280.5 6298.5 6165.3 6206.2 6211.1 6217.8 6221.2 6020.2 6117.3 6138.3 6142.6 6233.8 4526.6 4607.2 4848.2 4863.7 4872.2 4408.2 4430.2 4450.0 4467.3 4481.5 5677.3 5694.7 5706.6 5714.7 5716.5 6110.8 6187.8 6205.2 6258.3 6307.8 5669.5 5678.2 5684.3 5689.8 5693.8 4448.9 5210.0 5375.0 5382.2 5390.2 6230.7 6237.4 6308.9 6348.6 6356.6 5091.7 6250.9 6293.4 6304.9 6315.0 5317.4 6288.6 6329.1 6335.8 6337.7 6079.9 6128.7 6134.5 6175.8 6177.1 5041.5 5512.9 6196.9 6231.2 6253.8 5478.5 5529.9 5960.5 5991.8 5994.8 5871.8 5958.2 5985.3 6006.7 6061.1 5868.2 5870.7 5872.2 5872.8 5883.5 5982.9 5985.9 5985.9 5986.1 6023.6 5768.8 5885.8 5944.1 5987.3 5988.0 03:23:22 INFO - PROCESS | 1664 | 03:23:22 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:66887.20 Median:19685.68 stddev:141547.51 (719.0%) stddev-sans-first:141856.89 03:23:22 INFO - PROCESS | 1664 | Values: 522.1 528.5 532.8 545.5 550.6 29948.9 31646.4 38092.8 39286.2 39421.4 41459.0 41980.4 42057.6 42058.2 42090.9 9468.9 9698.5 9699.4 9719.0 9737.2 8675.7 8897.7 11705.9 11891.8 11903.3 11575.3 11629.8 11635.6 11639.9 11762.6 8603.4 11582.8 11676.1 11682.8 11700.8 11525.3 11659.3 11660.7 11678.8 11685.8 38327.0 38813.8 38850.6 38972.0 39018.6 3182.3 3544.4 3612.5 3667.6 3706.5 27519.3 28923.7 29047.9 29269.0 29285.0 2729.6 2750.8 2760.1 2780.0 2780.4 482751.0 485367.0 485602.0 486173.0 487472.0 62063.4 62474.0 62479.0 62618.0 62645.7 69934.7 70936.9 71099.6 71159.6 71288.9 17688.4 17800.8 17956.8 17961.3 17987.5 12572.2 12798.7 13537.0 13665.0 13830.2 19423.0 19979.5 19990.2 20042.9 20089.9 8367.3 8442.7 8488.3 8508.7 8520.2 916.8 956.6 989.2 1008.7 1036.7 29668.9 30655.4 30903.7 32740.0 33191.8 1020.1 1090.1 1131.2 1138.1 1156.6 14783.6 14846.2 15005.8 15055.5 15163.8 616239.0 624611.9 625620.0 626455.0 627392.0 59951.4 60637.4 60655.4 60665.4 60761.1 1296.8 1519.1 1530.1 1538.0 1557.7 28852.0 29150.4 29247.8 29694.6 29784.9 52734.5 53318.7 53618.7 53700.7 53715.5 462245.7 470837.0 471221.0 471465.1 471786.0 23239.8 26442.3 26834.0 26837.7 27195.5 41429.8 42307.9 44261.6 44700.0 45711.0 35394.6 35758.8 37047.0 37689.6 37718.8 30564.7 36295.8 36608.0 36645.6 37477.9 36485.8 36675.6 36750.0 36826.8 37286.0 31065.4 32630.3 32636.5 32638.5 32675.5 24486.0 24779.5 24810.8 25191.0 25261.4 03:23:22 INFO - PROCESS | 1664 | 03:23:22 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:32701.47 Median:20739.69 stddev:91052.15 (439.0%) stddev-sans-first:91275.25 03:23:22 INFO - PROCESS | 1664 | Values: 391.4 394.9 395.0 396.0 400.3 19022.3 19753.0 20484.9 20508.9 20765.7 19881.2 20446.0 20730.8 20748.6 20755.9 5378.7 5708.5 6000.2 6070.6 6081.7 5961.0 5972.3 6024.6 6025.5 6027.2 5751.9 5963.9 5964.8 5967.3 6084.2 5955.4 5978.7 6003.2 6004.0 6006.8 5692.0 5723.8 5788.5 5788.9 5795.4 13242.4 13408.5 13469.2 13502.3 20428.0 1872.3 1988.3 2081.0 2329.6 2755.9 9570.8 9947.5 9958.6 12986.5 14002.9 1689.1 1767.2 1780.6 1828.0 1885.1 114304.9 115070.4 115377.0 116566.4 120034.8 11292.8 11456.4 11478.9 11569.2 11697.9 14544.6 14594.1 14624.9 14641.4 14647.3 8595.9 8762.7 8769.6 8849.2 8907.6 8884.1 8897.5 8910.3 9022.3 9026.1 3762.7 3837.2 4450.2 4474.6 4581.2 1805.6 1881.5 1928.3 1942.0 1982.3 740.7 756.7 989.5 1021.3 1042.0 19653.4 19992.9 20065.0 20166.3 20954.7 912.0 987.6 997.2 1015.9 1087.7 10374.6 10420.3 10424.9 10462.3 10657.4 487497.0 495611.0 495903.0 595595.0 626915.0 88686.0 101708.5 105476.5 123339.0 149709.5 1037.8 1047.7 1050.2 1081.1 1082.1 14487.9 17577.2 17731.6 17752.6 18230.8 36340.8 52590.0 53704.5 54727.5 57772.6 80020.0 82608.0 83589.6 84004.7 84255.0 7927.0 8042.2 8154.8 8214.5 8223.9 9102.7 12164.3 12491.6 12617.2 12666.7 10557.5 10909.9 11135.0 11162.6 11265.3 10675.0 10720.8 10798.0 10987.6 11118.5 11038.4 11195.8 11335.0 11386.2 11448.9 7713.0 7951.4 8095.8 8173.1 8386.1 8282.0 10646.8 10941.1 11149.4 11272.7 03:23:22 INFO - PROCESS | 1664 | 03:23:22 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5654.96 Median:5043.44 stddev:3147.23 (62.4%) stddev-sans-first:3140.13 03:23:22 INFO - PROCESS | 1664 | Values: 1441.8 1553.9 1579.2 1823.9 1960.3 7938.8 8614.4 8800.5 8815.1 8817.4 8467.9 8718.2 8721.4 8723.7 8726.4 2426.6 2458.7 2458.9 2459.5 2460.5 2403.9 2455.5 2467.4 2468.3 2473.6 2428.3 2462.7 2476.9 2480.0 2482.5 2423.3 2460.3 2469.8 2470.8 2472.8 2402.1 2437.8 2451.2 2452.5 2461.2 8533.8 8757.6 8819.9 8835.6 8871.9 5446.4 5533.1 5583.7 5596.7 5600.0 3535.6 3602.3 3653.9 3676.9 3683.2 1024.7 1049.3 1056.4 1063.4 1073.6 11717.3 11812.9 11936.0 11949.2 11977.2 4073.8 4098.4 4489.7 4704.2 4715.9 9397.3 9409.0 9420.9 9425.1 9436.2 5377.5 5378.6 5383.4 5402.1 5408.9 3929.9 4435.3 4858.1 4988.3 4994.2 3837.1 3851.6 3894.4 3934.6 3939.0 1197.4 1199.3 1203.5 1219.1 1219.2 707.3 798.8 883.4 910.7 925.6 5464.5 5740.3 5788.1 5810.2 5907.3 928.2 951.8 952.8 977.8 1007.5 3620.7 3691.6 3844.0 3912.1 3984.8 10550.8 10731.9 10739.0 10764.7 10928.5 10575.3 10756.2 10777.1 10779.1 10819.6 6561.6 8188.8 8426.5 8428.2 8457.9 5612.2 5724.7 5772.6 5857.9 5858.1 9413.0 9465.6 9765.5 9767.0 9777.3 8509.5 9257.6 9851.1 9914.8 10107.4 7452.0 7538.8 7705.3 7718.7 7720.8 8212.5 8219.4 8241.9 8247.3 8293.9 6079.9 7037.6 7195.0 7424.1 7498.4 7065.6 7217.4 7257.3 7288.7 7377.7 6829.1 6911.6 6913.4 6917.4 6926.8 4690.8 4721.3 4730.7 4860.0 4922.8 4791.9 4838.6 4857.7 4868.7 5092.7 03:23:22 INFO - PROCESS | 1664 | 03:23:22 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5580.27 Median:6408.99 stddev:1788.01 (27.9%) stddev-sans-first:1748.81 03:23:22 INFO - PROCESS | 1664 | Values: 315.2 321.0 324.2 328.3 332.4 6365.1 6427.3 6455.3 6478.4 6489.9 6401.4 6416.6 6420.8 6441.6 6442.4 4523.2 4610.9 4638.8 4719.6 4744.2 4683.1 4739.4 4739.9 4771.0 4776.0 4694.9 4697.0 4703.7 4705.0 4715.3 4521.3 4540.4 4648.1 4683.1 4691.9 3442.6 3504.6 4044.3 4055.7 4056.0 5899.5 6172.6 6177.8 6210.6 6245.8 5576.9 5579.9 5593.7 5603.7 5605.8 6805.2 6821.9 6833.9 6836.5 6839.5 4301.9 4445.7 4449.6 4466.2 4469.2 6976.0 7184.4 7215.7 7223.4 7228.9 4531.9 4539.0 4544.2 4544.8 4550.4 4585.4 4612.9 4616.5 4616.6 4617.0 5455.8 5457.4 5458.9 5461.4 5466.5 6584.7 6854.6 6860.9 6875.1 6906.4 1582.0 1587.2 1605.2 1608.5 1610.3 947.1 995.9 1000.6 1021.7 1022.7 6226.1 6260.3 6272.5 6280.0 6284.1 7146.5 7169.6 7173.5 7181.6 7184.1 6447.8 6464.9 6468.2 6471.2 6482.2 3734.9 3779.4 3781.5 3782.2 3782.9 7103.8 7432.8 7443.5 7448.4 7489.2 6035.3 7352.5 7357.6 7365.2 7365.6 6065.7 7428.5 7439.7 7443.0 7450.3 5813.4 7104.3 7138.3 7150.2 7162.6 6686.4 7050.2 7054.0 7064.2 7064.5 6089.0 7281.1 7408.6 7545.8 7653.3 3883.0 4173.3 4531.4 4584.3 4584.8 6794.9 7087.1 7094.0 7097.6 7098.5 6560.1 6854.4 6855.9 6865.3 6901.8 5273.7 6812.0 6824.8 6840.5 6860.8 6700.5 6709.1 6709.4 6712.4 6713.2 5586.4 6869.2 6872.5 6873.0 6891.7 6449.3 6484.1 6602.5 6615.3 6725.5 03:23:22 INFO - PROCESS | 1664 | 03:23:22 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18384.70 Median:650.77 stddev:25841.39 (3970.9%) stddev-sans-first:25879.52 03:23:22 INFO - PROCESS | 1664 | Values: 635.5 672.9 690.5 723.3 727.8 14892.6 15072.2 15072.6 15115.8 15118.2 13627.5 13673.8 13704.2 13704.5 13740.1 708.4 724.6 885.0 893.9 913.4 910.3 917.8 919.4 919.7 919.8 953.3 974.8 975.2 975.8 976.4 752.3 950.2 965.8 971.3 971.5 714.3 715.6 716.3 716.6 717.7 14666.7 14784.9 14787.2 14795.3 14795.9 12575.8 12740.4 12909.7 13043.7 13099.9 9986.7 10018.6 10106.5 10229.9 10236.1 3445.8 3454.1 3508.0 3574.5 3575.6 77829.8 78227.0 78280.0 78344.6 79880.4 8097.4 9615.5 9744.9 9750.8 9826.7 21735.2 21811.3 21813.7 21834.5 21847.7 7146.0 7204.4 7280.9 7288.3 7482.7 3863.1 3958.4 4029.0 4038.8 4185.6 3550.3 3600.8 3603.1 3613.6 3613.7 618.1 648.8 650.3 651.2 656.7 524.4 554.9 571.9 577.2 607.6 7951.0 8015.4 8177.0 8211.7 8232.4 526.8 529.8 552.6 575.9 602.7 3607.6 3701.9 3749.2 3786.2 3961.6 89968.6 92677.0 92898.6 93217.6 93574.0 75569.0 78168.0 78942.0 79944.6 81152.2 67157.7 67252.3 67328.0 68373.3 69178.7 7043.6 8199.6 8283.3 8351.1 8553.4 48947.0 52192.7 52385.0 53821.2 54534.7 66494.3 70006.0 74661.0 74775.0 75705.0 11268.3 11282.3 11410.5 11560.7 11845.7 11810.5 11965.6 11997.8 12110.0 12536.0 8840.7 11081.3 11319.8 11507.3 11970.2 9983.1 11223.8 11395.5 11525.3 11599.4 11087.6 11144.5 11231.2 11588.5 11760.4 9696.7 9807.7 9834.4 9894.3 9896.8 9721.0 9811.7 9816.5 9867.7 9891.3 03:23:22 INFO - PROCESS | 1664 | -------- Summary: end -------- 03:23:22 INFO - PROCESS | 1664 | 03:23:23 INFO - TEST-INFO | 1664: exit 0 03:23:23 INFO - TEST-OK | dromaeo_css | took 1115502ms 03:23:23 INFO - TEST-START | kraken 03:23:23 INFO - operating with platform_type : mac_ 03:23:23 INFO - Initialising browser for kraken test... 03:23:23 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpizyw85/profile http://localhost:49276/getInfo.html) 03:23:25 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 03:23:25 INFO - PROCESS | 1673 | colorDepth:24 03:23:25 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 03:23:25 INFO - PROCESS | 1673 | __metrics 03:23:25 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:23:25 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:23:26 INFO - TEST-INFO | 1673: exit 0 03:23:26 INFO - Browser initialized. 03:23:26 INFO - Running cycle 1/1 for kraken test... 03:23:26 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpizyw85/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:23:38 INFO - PROCESS | 1676 | RSS: Main: 211460096 03:23:38 INFO - PROCESS | 1676 | 03:23:41 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 03:23:41 INFO - PROCESS | 1676 | RSS: Main: 271380480 03:23:41 INFO - PROCESS | 1676 | 03:23:44 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 03:23:45 INFO - PROCESS | 1676 | RSS: Main: 285925376 03:23:45 INFO - PROCESS | 1676 | 03:23:48 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 03:23:49 INFO - PROCESS | 1676 | RSS: Main: 373952512 03:23:49 INFO - PROCESS | 1676 | 03:23:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 03:23:51 INFO - PROCESS | 1676 | RSS: Main: 278224896 03:23:51 INFO - PROCESS | 1676 | 03:23:53 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:23:54 INFO - PROCESS | 1676 | RSS: Main: 278806528 03:23:54 INFO - PROCESS | 1676 | 03:23:57 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 03:23:57 INFO - PROCESS | 1676 | RSS: Main: 317276160 03:23:57 INFO - PROCESS | 1676 | 03:24:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 03:24:01 INFO - PROCESS | 1676 | RSS: Main: 319561728 03:24:01 INFO - PROCESS | 1676 | 03:24:04 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 03:24:04 INFO - PROCESS | 1676 | RSS: Main: 318095360 03:24:04 INFO - PROCESS | 1676 | 03:24:06 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:24:06 INFO - PROCESS | 1676 | RSS: Main: 309227520 03:24:06 INFO - PROCESS | 1676 | 03:24:08 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:24:09 INFO - PROCESS | 1676 | RSS: Main: 315633664 03:24:09 INFO - PROCESS | 1676 | 03:24:11 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:24:12 INFO - PROCESS | 1676 | RSS: Main: 319102976 03:24:12 INFO - PROCESS | 1676 | 03:24:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:24:15 INFO - PROCESS | 1676 | RSS: Main: 323960832 03:24:15 INFO - PROCESS | 1676 | 03:24:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:24:19 INFO - PROCESS | 1676 | RSS: Main: 322031616 03:24:19 INFO - PROCESS | 1676 | 03:24:20 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 03:24:21 INFO - PROCESS | 1676 | RSS: Main: 386039808 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | __start_tp_report 03:24:21 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 03:24:21 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 03:24:21 INFO - PROCESS | 1676 | |i|pagename|runs| 03:24:21 INFO - PROCESS | 1676 | |0;ai-astar;115;114;122;112;114;113;113;113;116;133 03:24:21 INFO - PROCESS | 1676 | |1;audio-beat-detection;156;121;142;166;119;140;122;173;184;143 03:24:21 INFO - PROCESS | 1676 | |2;audio-dft;185;188;184;184;184;187;185;185;185;183 03:24:21 INFO - PROCESS | 1676 | |3;audio-fft;69;82;73;154;55;55;63;64;138;57 03:24:21 INFO - PROCESS | 1676 | |4;audio-oscillator;89;88;139;76;83;88;121;88;112;160 03:24:21 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;91;87;91;88;90;88;90;89;91;87 03:24:21 INFO - PROCESS | 1676 | |6;imaging-darkroom;96;96;96;96;96;96;97;97;96;97 03:24:21 INFO - PROCESS | 1676 | |7;imaging-desaturate;85;85;85;86;84;86;86;87;85;86 03:24:21 INFO - PROCESS | 1676 | |8;json-parse-financial;60;63;67;66;67;64;64;84;58;58 03:24:21 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;63;66;64;76;78;70;67;68;67;67 03:24:21 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;81;81;80;80;81;80;80;79;82;85 03:24:21 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;133;130;137;136;138;125;142;127;166;175 03:24:21 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;165;166;166;167;165;165;164;163;167;166 03:24:21 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;69;67;66;68;66;67;67;67;67;67 03:24:21 INFO - PROCESS | 1676 | __end_tp_report 03:24:21 INFO - PROCESS | 1676 | __start_cc_report 03:24:21 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2006 03:24:21 INFO - PROCESS | 1676 | __end_cc_report 03:24:21 INFO - PROCESS | 1676 | __startTimestamp1463653461081__endTimestamp 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | ------- Summary: start ------- 03:24:21 INFO - PROCESS | 1676 | Number of tests: 14 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:116.50 Median:114.50 stddev:6.45 (5.6%) stddev-sans-first:6.82 03:24:21 INFO - PROCESS | 1676 | Values: 115.0 114.0 122.0 112.0 114.0 113.0 113.0 113.0 116.0 133.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:146.60 Median:149.50 stddev:22.76 (15.2%) stddev-sans-first:23.88 03:24:21 INFO - PROCESS | 1676 | Values: 156.0 121.0 142.0 166.0 119.0 140.0 122.0 173.0 184.0 143.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.00 stddev:1.49 (0.8%) stddev-sans-first:1.58 03:24:21 INFO - PROCESS | 1676 | Values: 185.0 188.0 184.0 184.0 184.0 187.0 185.0 185.0 185.0 183.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:81.00 Median:63.50 stddev:35.48 (55.9%) stddev-sans-first:37.36 03:24:21 INFO - PROCESS | 1676 | Values: 69.0 82.0 73.0 154.0 55.0 55.0 63.0 64.0 138.0 57.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:104.40 Median:85.50 stddev:27.75 (32.5%) stddev-sans-first:28.87 03:24:21 INFO - PROCESS | 1676 | Values: 89.0 88.0 139.0 76.0 83.0 88.0 121.0 88.0 112.0 160.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:90.00 stddev:1.62 (1.8%) stddev-sans-first:1.58 03:24:21 INFO - PROCESS | 1676 | Values: 91.0 87.0 91.0 88.0 90.0 88.0 90.0 89.0 91.0 87.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:96.30 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 03:24:21 INFO - PROCESS | 1676 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 97.0 97.0 96.0 97.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:0.85 (1.0%) stddev-sans-first:0.88 03:24:21 INFO - PROCESS | 1676 | Values: 85.0 85.0 85.0 86.0 84.0 86.0 86.0 87.0 85.0 86.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.00 stddev:7.45 (11.5%) stddev-sans-first:7.66 03:24:21 INFO - PROCESS | 1676 | Values: 60.0 63.0 67.0 66.0 67.0 64.0 64.0 84.0 58.0 58.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:67.50 stddev:4.86 (7.2%) stddev-sans-first:4.71 03:24:21 INFO - PROCESS | 1676 | Values: 63.0 66.0 64.0 76.0 78.0 70.0 67.0 68.0 67.0 67.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:1.66 (2.1%) stddev-sans-first:1.76 03:24:21 INFO - PROCESS | 1676 | Values: 81.0 81.0 80.0 80.0 81.0 80.0 80.0 79.0 82.0 85.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:140.90 Median:137.50 stddev:16.56 (12.0%) stddev-sans-first:17.32 03:24:21 INFO - PROCESS | 1676 | Values: 133.0 130.0 137.0 136.0 138.0 125.0 142.0 127.0 166.0 175.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:166.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 03:24:21 INFO - PROCESS | 1676 | Values: 165.0 166.0 166.0 167.0 165.0 165.0 164.0 163.0 167.0 166.0 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.60 03:24:21 INFO - PROCESS | 1676 | Values: 69.0 67.0 66.0 68.0 66.0 67.0 67.0 67.0 67.0 67.0 03:24:21 INFO - PROCESS | 1676 | -------- Summary: end -------- 03:24:21 INFO - PROCESS | 1676 | 03:24:21 INFO - TEST-INFO | 1676: exit 0 03:24:21 INFO - TEST-OK | kraken | took 58705ms 03:24:21 INFO - SUITE-END | took 1174s 03:24:22 INFO - Completed test suite (00:19:34) 03:24:22 INFO - javascript benchmark 03:24:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3386.2888793353395, 3451.102713294541, 3458.5676790401194, 3473.270613171234, 3519.102628829648, 5608.719564021798, 5634.63374880633, 5663.801766938158, 5673.120666296731, 5690.943090569098, 5648.124540696199, 5688.118341657039, 5695.658260504372, 5700.315962084545, 5701.486866182043, 5059.2158215476475, 5089.770960306795, 5100.413452452978, 5182.818601348954, 5214.791408343662, 5202.219667049935, 5207.713575753332, 5209.479052094798, 5213.32226810516, 5222.216667499867, 5114.667546609477, 5144.588432925357, 5152.201408781645, 5163.535281824635, 5186.118359878829, 5179.792808287645, 5202.713850738208, 5214.4524824893215, 5218.817341393033, 5259.368875734898, 5021.0, 5058.215976523645, 5068.290439338495, 5149.0, 5153.845384638467, 4623.884402889955, 5499.340079190482, 5522.66863988162, 5544.695041772701, 5545.334559852803, 6215.782447614311, 6280.15217945576, 6281.309056003836, 6285.685715714196, 6285.685715714196, 6072.060840294228, 6222.595531290452, 6283.214598175228, 6310.779122730728, 6338.0492926061, 5742.08126699726, 5759.395263497357, 5763.3084029915835, 5778.855528611818, 5779.884402311972, 6308.337624549448, 6321.810345689545, 6327.873442531123, 6333.873322533524, 6344.8413789654705, 5051.722155281494, 6011.549133814981, 6082.361352058058, 6095.634261944208, 6175.3515880832765, 6260.906086408708, 6274.058891166362, 6275.811725648238, 6280.497560195174, 6298.527610429236, 6165.32181460039, 6206.162168107247, 6211.130441738161, 6217.813465596039, 6221.222347206599, 6020.187274717945, 6117.279373342116, 6138.296437970319, 6142.590862071594, 6233.781817636361, 4526.637868970474, 4607.216773148572, 4848.200046992278, 4863.70817750929, 4872.171730805771, 4408.228560002051, 4430.158269928703, 4449.9555004450185, 4467.285234362486, 4481.462224533012, 5677.261955945701, 5694.715264236772, 5706.629069110578, 5714.742836572281, 5716.456936591017, 6110.755569777248, 6187.752489900354, 6205.193324867829, 6258.311585725566, 6307.779227727007, 5669.518090962232, 5678.205051292823, 5684.34630017545, 5689.7724091036725, 5693.772249110072, 4448.9110217796115, 5209.973950130301, 5374.973125134349, 5382.192671099367, 5390.245365648812, 6230.657313847782, 6237.376262373727, 6308.905366419415, 6348.587341822859, 6356.555041147168, 5091.745412729275, 6250.906236406367, 6293.37066293367, 6304.936950630435, 6315.021171718391, 5317.361916570037, 6288.5598008138995, 6329.082283068838, 6335.841603959938, 6337.683115844282, 6079.908801367895, 6128.693565321627, 6134.528431832502, 6175.814725558241, 6177.135201071764, 5041.546260836542, 5512.91730624033, 6196.876062478816, 6231.158793562811, 6253.812385628256, 5478.534324582455, 5529.894021195857, 5960.4635582797755, 5991.760329586942, 5994.820155395345, 5871.823845284648, 5958.19564358806, 5985.341612422543, 6006.669633170218, 6061.121137435135, 5868.23712917318, 5870.706464676835, 5872.1778950947755, 5872.823815285548, 5883.470487656132, 5982.910256346246, 5985.910211346746, 5985.940140598538, 5986.102084687332, 6023.608465449732, 5768.798092066925, 5885.793997210077, 5944.138099975566, 5987.2815262168815, 5988.0], "value": 5627.759209491738, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.1280461629079, 528.4741682026385, 532.7709085093413, 545.5172172627224, 550.5650536076499, 29948.85025574872, 31646.367072658533, 38092.809535952285, 39286.21427571447, 39421.40867886984, 41458.963525911866, 41980.37029444561, 42057.579424205745, 42058.15883682325, 42090.947726306855, 9468.905310946888, 9698.515074246296, 9699.36954097985, 9719.0, 9737.172340351044, 8675.69635062773, 8897.733068007938, 11705.882941170607, 11891.762164756743, 11903.345316007617, 11575.305481671085, 11629.825552616718, 11635.592754253603, 11639.941800290986, 11762.588309409173, 8603.354748393833, 11582.768344633103, 11676.065914726843, 11682.824757628643, 11700.824487632692, 11525.250858694159, 11659.300441973508, 11660.650180494515, 11678.824817627743, 11685.824712629317, 38327.0, 38813.80593097045, 38850.61149388498, 38972.0, 39018.60981390207, 3182.347618738153, 3544.3620148373284, 3612.530371051773, 3667.559892812853, 3706.5181526401666, 27519.31201719973, 28923.71076289231, 29047.854760726274, 29269.0, 29285.0, 2729.6178535005115, 2750.752432281104, 2760.061579063128, 2779.999200287895, 2780.374415756439, 482751.0, 485367.0, 485602.0, 486173.0, 487472.0, 62063.37936620576, 62474.0, 62479.0, 62618.0, 62645.74708505804, 69934.65032674804, 70936.93594596085, 71099.64450177716, 71159.64420177866, 71288.93066603901, 17688.380906668463, 17800.821991779918, 17956.820431795775, 17961.281548737905, 17987.460376188734, 12572.182808117443, 12798.680032999064, 13537.0, 13665.0, 13830.23933683657, 19423.046378653104, 19979.500512487302, 19990.20039198445, 20042.89978550127, 20089.895055772075, 8367.288780453608, 8442.662293508314, 8488.278496327757, 8508.702195423131, 8520.233179013794, 916.7753900294471, 956.5886668732512, 989.2284018465608, 1008.7074748323051, 1036.7097212780434, 29668.851655742015, 30655.386892262475, 30903.690963090532, 32740.0, 33191.83404082964, 1020.1277907389224, 1090.0734375780523, 1131.1685910855515, 1138.1463902073444, 1156.5894107591853, 14783.556493305217, 14846.18345990981, 15005.849941500664, 15055.54833355001, 15163.848361516464, 616239.0, 624611.8769406215, 625620.0, 626455.0, 627392.0, 59951.400485994585, 60637.393626064055, 60655.393446064976, 60665.393346066856, 60761.08858367128, 1296.8378952630922, 1519.1037288000132, 1530.051368151753, 1537.9923100384426, 1557.696249231389, 28851.990180343586, 29150.416991659626, 29247.78274389858, 29694.55458168086, 29784.851075744482, 52734.472655272955, 53318.73450123903, 53618.65953351197, 53700.73149634227, 53715.46284537105, 462245.688771554, 470837.0, 471221.0, 471465.0694211851, 471786.0, 23239.767602323762, 26442.338941526617, 26834.0, 26837.731622684303, 27195.456090877677, 41429.79285103676, 42307.94230144271, 44261.55738442703, 44700.0, 45711.0, 35394.64605353914, 35758.8212058938, 37047.0, 37689.62310376971, 37718.8114059428, 30564.694353056184, 36295.818520907225, 36608.0, 36645.63354366529, 37477.875663730134, 36485.817570911975, 36675.633243667224, 36750.0, 36826.8158659205, 37286.0, 31065.37869242648, 32630.34739305248, 32636.510452342758, 32638.51042234416, 32675.509867351535, 24486.0, 24779.50440991206, 24810.751892480843, 25191.0, 25261.368465788502], "value": 20255.662107501288, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.3679407756473, 394.8578511735776, 394.9842006319747, 396.0415793779055, 400.251529639574, 19022.334218302363, 19753.0, 20484.89757551214, 20508.89745551272, 20765.688514672293, 19881.20475180991, 20445.97770111496, 20730.792692073075, 20748.585028299425, 20755.896220518913, 5378.650387724795, 5708.514776244025, 6000.249968753906, 6070.635761854281, 6081.726322315484, 5961.016432288668, 5972.283326000886, 6024.608400453978, 6025.517958563326, 6027.246594175728, 5751.884962300773, 5963.85090372742, 5964.821055368324, 5967.283925928895, 6084.1786358841655, 5955.374685658008, 5978.671173085479, 6003.249593800775, 6004.039353703431, 6006.8498287542725, 5691.9754444200025, 5723.771049158028, 5788.450097240755, 5788.942110578882, 5795.362510123883, 13242.404091815892, 13408.530701425403, 13469.191848489121, 13502.324883755773, 20428.0, 1872.3353209610527, 1988.3040935672543, 2081.0219196977396, 2329.6389059695775, 2755.931101722453, 9570.760730981781, 9947.502624868728, 9958.601655933753, 12986.480540778357, 14002.929985350009, 1689.0541296873666, 1767.1517671517743, 1780.563761878335, 1827.954301142482, 1885.0668918885103, 114304.85695143025, 115070.42464787707, 115377.0, 116566.41716791446, 120034.7996520041, 11292.830607540893, 11456.3698996556, 11478.94260528692, 11569.247998880046, 11697.883021169679, 14544.563663090124, 14594.124352538882, 14624.85375146235, 14641.414343426357, 14647.26763661834, 8595.914040859636, 8762.693305734269, 8769.561521924006, 8849.20357167858, 8907.55462226886, 8884.111588841193, 8897.466152030898, 8910.307041662063, 9022.278217742696, 9026.097390260922, 3762.6613604775703, 3837.213371258885, 4450.1767173073085, 4474.597286244189, 4581.152486789954, 1805.5847155154388, 1881.5014021284371, 1928.257620815996, 1941.9611607768047, 1982.3359174676646, 740.6629983357559, 756.6595032235406, 989.4755779436766, 1021.2646894236138, 1041.9892704077033, 19653.410397687807, 19992.90003549973, 20064.996750162434, 20166.294179703935, 20954.685679714818, 911.9908800912038, 987.5704068729982, 997.2420960070253, 1015.8527013582989, 1087.690008347617, 10374.636887708893, 10420.32267902584, 10424.895751042544, 10462.319949203278, 10657.360558366368, 487497.0, 495611.0, 495903.0, 595595.0, 626915.0, 88686.0, 101708.4572885669, 105476.47261763642, 123339.0, 149709.50290497174, 1037.8028174646945, 1047.7433028907967, 1050.1546255264586, 1081.0594782539242, 1082.1451053667672, 14487.85512144865, 17577.209025593624, 17731.645367092842, 17752.55618609545, 18230.81769182291, 36340.81829590835, 52590.0, 53704.46295536995, 54727.452725472234, 57772.555686108186, 80020.0, 82608.0, 83589.58205208935, 84004.73992889989, 84255.0, 7927.0, 8042.155573664681, 8154.75535733929, 8214.548199849065, 8223.876641850258, 9102.726918192464, 12164.330961797186, 12491.562795302121, 12617.242965422103, 12666.746665066832, 10557.524911378852, 10909.890901090888, 11135.0, 11162.609308674157, 11265.26775759573, 10674.956215415143, 10720.785584288427, 10798.028177464066, 10987.63765790359, 11118.49966751482, 11038.392888391216, 11195.832062518906, 11335.03652189573, 11386.202965792316, 11448.885511144781, 7712.9614351927885, 7951.3638908886, 8095.797605059921, 8173.141820108803, 8386.077531471412, 8282.0, 10646.8402973957, 10941.07000904932, 11149.442527873736, 11272.66182014541], "value": 9368.002165991016, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.7837324401346, 1553.9300731467085, 1579.218286947961, 1823.890566566006, 1960.2844961589014, 7938.84122317554, 8614.396992210548, 8800.515971621558, 8815.118488151182, 8817.426867253624, 8467.872981905277, 8718.171773681506, 8721.43310684805, 8723.65105395785, 8726.389152759293, 2426.599611064172, 2458.6926634170727, 2458.918076046543, 2459.4712136890566, 2460.458699086198, 2403.939901502468, 2455.45979884426, 2467.3584867934287, 2468.271859801363, 2473.5547601431736, 2428.2715185444385, 2462.741412151725, 2476.913308034219, 2480.0203919451806, 2482.466269752003, 2423.345696661909, 2460.3357093584723, 2469.8147638927153, 2470.789982851442, 2472.7898128659085, 2402.0992127952018, 2437.7805997460314, 2451.2156110044793, 2452.497238066192, 2461.1755062054235, 8533.786655333604, 8757.562121893943, 8819.867701984474, 8835.602397892111, 8871.911280887172, 5446.373667028302, 5533.114701647757, 5583.6928968906695, 5596.692181929992, 5600.019996500596, 3535.6464353564693, 3602.3155600435834, 3653.9269214615824, 3676.871309504181, 3683.1528748388023, 1024.6925922223304, 1049.2864851900704, 1056.4242487844067, 1063.3566692151214, 1073.5544748929185, 11717.296962182296, 11812.88187118135, 11935.985441237504, 11949.163558550817, 11977.22148060382, 4073.7555746655294, 4098.4467096941535, 4489.742871995846, 4704.200285951397, 4715.854286299726, 9397.295202859812, 9409.012053734396, 9420.858687119699, 9425.104615061558, 9436.150746432717, 5377.462253774591, 5378.596605254622, 5383.380911195184, 5402.054640437907, 5408.891822163535, 3929.9017524562114, 4435.334699795057, 4858.125537403231, 4988.276699878497, 4994.1509943309, 3837.1174629835295, 3851.557070936878, 3894.3963685628773, 3934.567197608261, 3938.980305098456, 1197.4012993503247, 1199.2744389644315, 1203.48851738011, 1219.091776626419, 1219.16487206264, 707.3315716647719, 798.8362385710915, 883.3595643158659, 910.7267819654078, 925.5834874306589, 5464.508194262536, 5740.339860916049, 5788.102844059177, 5810.244668193192, 5907.32065812437, 928.2110206324705, 951.8477043672979, 952.8284908716497, 977.8191034658611, 1007.496251874063, 3620.71034317254, 3691.630836916287, 3844.038990252437, 3912.139329347539, 3984.820683069269, 10550.841737373945, 10731.89268107309, 10739.0, 10764.677059688065, 10928.453577321103, 10575.312604680672, 10756.19328550362, 10777.137828973664, 10779.137668986463, 10819.56721731122, 6561.639109848908, 8188.836223275501, 8426.494410335277, 8428.199321064489, 8457.915420845837, 5612.214289999321, 5724.713764311685, 5772.567057470623, 5857.941420585739, 5858.062709966385, 9413.0, 9465.621375144918, 9765.511724413609, 9767.0, 9777.315587909063, 8509.531975741145, 9257.583408746488, 9851.113399794052, 9914.752131196778, 10107.39355638664, 7452.0, 7538.849223015399, 7705.30652241301, 7718.729844455417, 7720.7683769487, 8212.46618969765, 8219.383546233938, 8241.876371854547, 8247.298979586802, 8293.875591866006, 6079.939200608114, 7037.577745335297, 7194.964025179841, 7424.1462231844025, 7498.362639175732, 7065.57606543609, 7217.422606191387, 7257.310555497219, 7288.744893928687, 7377.668004939898, 6829.146356705412, 6911.550749201286, 6913.412359949461, 6917.412019978157, 6926.792196234121, 4690.835820746258, 4721.268203428474, 4730.692504987165, 4859.95140048595, 4922.803087876445, 4791.880202994814, 4838.612910967185, 4857.684250523705, 4868.707877527359, 5092.719900405513], "value": 4563.722383839204, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.1821024441574, 321.0096851214005, 324.18952618453875, 328.2646871008941, 332.41992722698893, 6365.140706004688, 6427.260865000527, 6455.289918109004, 6478.384553467425, 6489.935100648992, 6401.359864013596, 6416.615003099806, 6420.839479013027, 6441.645709485976, 6442.355764423567, 4523.163214805255, 4610.907781844361, 4638.814447422099, 4719.598834099089, 4744.169770290203, 4683.103343146031, 4739.383880095583, 4739.905201895977, 4770.97614511927, 4776.0, 4694.882627934312, 4697.0, 4703.670743047988, 4704.97685995022, 4715.269133284346, 4521.299198624219, 4540.364348991127, 4648.116857797041, 4683.110209060302, 4691.906161876744, 3442.638522955104, 3504.6144924058335, 4044.2518134144984, 4055.7363771355062, 4056.006278461796, 5899.498542623882, 6172.59878107926, 6177.81466556004, 6210.627362358273, 6245.7501699931945, 5576.9163462548095, 5579.944200558024, 5593.664380137164, 5603.719814009283, 5605.775768969236, 6805.217399999014, 6821.931780682179, 6833.897491537631, 6836.4530837532875, 6839.452843772487, 4301.892452688677, 4445.688801783876, 4449.644028477714, 4466.218411777926, 4469.240229161018, 6975.965120174366, 7184.425245980309, 7215.747448839318, 7223.386012188917, 7228.927710722878, 4531.932021019687, 4539.046800172001, 4544.18204723153, 4544.818207271672, 4550.385697930803, 4585.358049873021, 4612.884677883013, 4616.515265897099, 4616.630669546428, 4616.976915115403, 5455.8363249102595, 5457.426970168154, 5458.9454105458435, 5461.399246082928, 5466.508014278734, 6584.703688334036, 6854.6229957352825, 6860.862782744317, 6875.1437111979485, 6906.447484201251, 1581.9604509887115, 1587.1588058329105, 1605.1733357320988, 1608.4852847089053, 1610.2673283655909, 947.0860619502075, 995.9253056020827, 1000.6497725795913, 1021.7037059252882, 1022.7085280695115, 6226.128342032119, 6260.280067792171, 6272.529560282997, 6280.0, 6284.088807123033, 7146.499745017799, 7169.5698258104685, 7173.5337203081635, 7181.605011724301, 7184.066071410788, 6447.806565803034, 6464.903026454607, 6468.191476065492, 6471.2234531856475, 6482.189726284215, 3734.8506059757847, 3779.3575092234387, 3781.5084039074745, 3782.1868298315994, 3782.9432558511644, 7103.82240443993, 7432.777016689508, 7443.4789564729945, 7448.441366897504, 7489.176190619028, 6035.336113027563, 7352.485326027234, 7357.632118394166, 7365.15300740411, 7365.631718414058, 6065.727042283108, 7428.517146385468, 7439.702411903463, 7443.0, 7450.286434120075, 5813.3895940925595, 7104.254053324326, 7138.286171382821, 7150.213476517683, 7162.606056666933, 6686.364795344434, 7050.224475307815, 7054.0, 7064.152301723826, 7064.505484616027, 6089.0, 7281.0534630499815, 7408.555486670602, 7545.849083018418, 7653.3494652952895, 3883.0292426893275, 4173.269677806311, 4531.43357080365, 4584.335271385564, 4584.77076146198, 6794.864102717819, 7087.114110736158, 7094.006839042641, 7097.645117744195, 7098.467614929004, 6560.114384558024, 6854.417374523221, 6855.931440685529, 6865.313468653295, 6901.827454313682, 5273.683578985273, 6811.9659401702675, 6824.761133360308, 6840.452763778985, 6860.759873404407, 6700.4639628830555, 6709.060731497691, 6709.396154346132, 6712.362325579063, 6713.227978782307, 5586.357568879631, 6869.175698916162, 6872.518923675295, 6872.965635171792, 6891.655417229219, 6449.258335291502, 6484.092227088306, 6602.537822352581, 6615.338466153346, 6725.49558783103], "value": 4982.516567205576, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.5106567942685, 672.949528785341, 690.5442408010714, 723.2947875821075, 727.785303335516, 14892.553223403289, 15072.246387680627, 15072.623184420396, 15115.77326340103, 15118.244087795621, 13627.454901803942, 13673.794893076612, 13704.24626645534, 13704.52034178804, 13740.106893051945, 708.376628566863, 724.5507785173173, 884.9911500885005, 893.8972018217924, 913.4199783137722, 910.3400034974657, 917.8439665256923, 919.3610440743686, 919.7240827751648, 919.7562645898843, 953.323140570196, 974.8440249560072, 975.2490582251662, 975.7511834482198, 976.4190306767462, 752.255267285389, 950.1970834644715, 965.7778710896498, 971.2715463402377, 971.499677665999, 714.3463730686416, 715.588536591462, 716.3087620446295, 716.5987047253554, 717.7272636398187, 14666.706665866623, 14784.926075369554, 14787.186704731235, 14795.260236988108, 14795.852041479555, 12575.811362829565, 12740.426680799386, 12909.741805163938, 13043.673908152277, 13099.934500327532, 9986.679125991586, 10018.649347272883, 10106.545205465773, 10229.897701022968, 10236.129928956048, 3445.776024558396, 3454.136465883529, 3507.9649203508147, 3574.4995700601935, 3575.606683264839, 77829.83255251175, 78227.0, 78280.0, 78344.60827695826, 79880.40538175796, 8097.433179677366, 9615.51922403877, 9744.90255097454, 9750.75623109428, 9826.65606703762, 21735.23926662559, 21811.345659630235, 21813.67279490809, 21834.454138646663, 21847.672284915738, 7146.03528523653, 7204.387627051759, 7280.9271907280245, 7288.344049035612, 7482.738104166328, 3863.0535518798074, 3958.4062390641056, 4028.9597104028585, 4038.818253178555, 4185.6232939035635, 3550.289942011608, 3600.801955892399, 3603.135247540624, 3613.638636136365, 3613.6567026132475, 618.11300783376, 648.8053583924898, 650.2846868444767, 651.2478087808589, 656.6683824668512, 524.4257537995888, 554.9428339014185, 571.8799052199001, 577.2207519848255, 607.5716619783042, 7950.960245198737, 8015.398845086642, 8177.0, 8211.671533138608, 8232.423730338818, 526.8129813916005, 529.8383992882208, 552.6297380754904, 575.9164921086419, 602.734796689464, 3607.603163651996, 3701.9259614808093, 3749.1751814600743, 3786.1670432504807, 3961.564227934925, 89968.5501572488, 92677.0, 92898.60652090223, 93217.60173597402, 93574.0, 75569.0, 78168.0, 78942.0, 79944.60027699824, 81152.18847811564, 67157.66421167862, 67252.31869203309, 67327.99008014884, 68373.3162668367, 69178.65410673014, 7043.647817608997, 8199.631016604148, 8283.337333013465, 8351.123132071045, 8553.358498112791, 48947.0, 52192.69518262074, 52385.0, 53821.19268210902, 54534.7273263647, 66494.33505665074, 70005.95415302092, 74661.0, 74775.0, 75705.0, 11268.323900565665, 11282.266652667551, 11410.543578256777, 11560.710982225175, 11845.703857403634, 11810.468528916048, 11965.581204657796, 11997.820032699341, 12110.0, 12536.0, 8840.734777956672, 11081.27971681838, 11319.773604528027, 11507.309561426342, 11970.22193557416, 9983.051610097029, 11223.831642525205, 11395.544178232778, 11525.308481491138, 11599.420028998347, 11087.556497740323, 11144.498497567467, 11231.15766317513, 11588.536458541565, 11760.35318057515, 9696.660616878658, 9807.670343094745, 9834.360766550151, 9894.257930655374, 9896.752581185528, 9721.0, 9811.656592019244, 9816.509174541101, 9867.65463208813, 9891.307608467338], "value": 6876.026100500811, "unit": "score"}], "name": "dromaeo_css", "value": 7420.712733532033, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 122.0, 112.0, 114.0, 113.0, 113.0, 113.0, 116.0, 133.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 121.0, 142.0, 166.0, 119.0, 140.0, 122.0, 173.0, 184.0, 143.0], "value": 146.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 184.0, 184.0, 184.0, 187.0, 185.0, 185.0, 185.0, 183.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 82.0, 73.0, 154.0, 55.0, 55.0, 63.0, 64.0, 138.0, 57.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 139.0, 76.0, 83.0, 88.0, 121.0, 88.0, 112.0, 160.0], "value": 104.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 91.0, 88.0, 90.0, 88.0, 90.0, 89.0, 91.0, 87.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 97.0, 96.0, 97.0], "value": 96.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 86.0, 84.0, 86.0, 86.0, 87.0, 85.0, 86.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 67.0, 66.0, 67.0, 64.0, 64.0, 84.0, 58.0, 58.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 64.0, 76.0, 78.0, 70.0, 67.0, 68.0, 67.0, 67.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 80.0, 81.0, 80.0, 80.0, 79.0, 82.0, 85.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 130.0, 137.0, 136.0, 138.0, 125.0, 142.0, 127.0, 166.0, 175.0], "value": 140.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 166.0, 167.0, 165.0, 165.0, 164.0, 163.0, 167.0, 166.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 66.0, 68.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1492.5000000000002, "alertThreshold": 2.0}]} 03:24:22 INFO - Return code: 0 03:24:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:24:22 ERROR - # TBPL SUCCESS # 03:24:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:24:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:24:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:24:22 INFO - 2016-05-19 03:24:22.401 screenresolution[1680:10137] starting screenresolution argv=screenresolution get 03:24:22 INFO - 2016-05-19 03:24:22.404 screenresolution[1680:10137] Display 0: 1600x1200x32@60 03:24:22 INFO - 2016-05-19 03:24:22.410 screenresolution[1681:10141] starting screenresolution argv=screenresolution list 03:24:22 INFO - Available Modes on Display 0 03:24:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:24:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:24:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:24:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:24:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:24:22 INFO - Intel Iris: 03:24:22 INFO - Chipset Model: Intel Iris 03:24:22 INFO - Type: GPU 03:24:22 INFO - Bus: Built-In 03:24:22 INFO - VRAM (Dynamic, Max): 1536 MB 03:24:22 INFO - Vendor: Intel (0x8086) 03:24:22 INFO - Device ID: 0x0a2e 03:24:22 INFO - Revision ID: 0x0009 03:24:22 INFO - Displays: 03:24:22 INFO - Display: 03:24:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:24:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:24:22 INFO - Display Serial Number: 1600x1200 60 03:24:22 INFO - Main Display: Yes 03:24:22 INFO - Mirror: Off 03:24:22 INFO - Online: Yes 03:24:22 INFO - Rotation: Supported 03:24:22 INFO - Return code: 0 03:24:22 INFO - Running post-action listener: _resource_record_post_action 03:24:22 INFO - Running post-run listener: _resource_record_post_run 03:24:23 INFO - Total resource usage - Wall time: 1209s; CPU: 52.0%; Read bytes: 37889536; Write bytes: 653219328; Read time: 395; Write time: 1876 03:24:23 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 354390528; Write bytes: 350983680; Read time: 28796; Write time: 658 03:24:23 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 36107776; Write bytes: 296685568; Read time: 376; Write time: 1195 03:24:23 INFO - Running post-run listener: _upload_blobber_files 03:24:23 INFO - Blob upload gear active. 03:24:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:24:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:24:23 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:24:23 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:24:24 INFO - (blobuploader) - INFO - Open directory for files ... 03:24:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:24:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:24:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:24:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:24:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:24:24 INFO - (blobuploader) - INFO - Done attempting. 03:24:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:24:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:24:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:24:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:24:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:24:25 INFO - (blobuploader) - INFO - Done attempting. 03:24:25 INFO - (blobuploader) - INFO - Iteration through files over. 03:24:25 INFO - Return code: 0 03:24:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:24:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:24:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/67bb8c0b050bbb25a02f190cf15aa88bb4ce757de7e2551008b9662367594fb68da744e336fbeb5667404ab53bbb8df5f05298ff7a9f53ad9f2e9b3329f8a426", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/469f0addc4e0b4999007eb090566c3609d373a7d60a8f6db0b9fa4de02ceb9e9e08d71addeaa3e1ef4e96f6b10f2fb2365d44bbb7f00722fad5ce2f76304f2ae"} 03:24:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:24:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:24:25 INFO - Contents: 03:24:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/67bb8c0b050bbb25a02f190cf15aa88bb4ce757de7e2551008b9662367594fb68da744e336fbeb5667404ab53bbb8df5f05298ff7a9f53ad9f2e9b3329f8a426", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/469f0addc4e0b4999007eb090566c3609d373a7d60a8f6db0b9fa4de02ceb9e9e08d71addeaa3e1ef4e96f6b10f2fb2365d44bbb7f00722fad5ce2f76304f2ae"} 03:24:25 INFO - Running post-run listener: copy_logs_to_upload_dir 03:24:25 INFO - Copying logs to upload dir... 03:24:25 INFO - mkdir: /builds/slave/test/build/upload/logs 03:24:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.805639 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-19 03:24:25.891405) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 03:24:25.895086) ========= 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.ZL10j1asaX/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.aWVe9s1u6G/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/Mozilla-Aurora/sha512/67bb8c0b050bbb25a02f190cf15aa88bb4ce757de7e2551008b9662367594fb68da744e336fbeb5667404ab53bbb8df5f05298ff7a9f53ad9f2e9b3329f8a426", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/469f0addc4e0b4999007eb090566c3609d373a7d60a8f6db0b9fa4de02ceb9e9e08d71addeaa3e1ef4e96f6b10f2fb2365d44bbb7f00722fad5ce2f76304f2ae"} build_url:https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011265 build_url: 'https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/67bb8c0b050bbb25a02f190cf15aa88bb4ce757de7e2551008b9662367594fb68da744e336fbeb5667404ab53bbb8df5f05298ff7a9f53ad9f2e9b3329f8a426", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/469f0addc4e0b4999007eb090566c3609d373a7d60a8f6db0b9fa4de02ceb9e9e08d71addeaa3e1ef4e96f6b10f2fb2365d44bbb7f00722fad5ce2f76304f2ae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 03:24:25.922835) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:24:25.923121) ========= 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.ZL10j1asaX/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.aWVe9s1u6G/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.004963 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:24:25.971140) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 03:24:25.971415) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 03:24:25.971705) ========= ========= Total master_lag: 0.15 =========