builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0008 starttime: 1467027893.51 results: success (0) buildid: 20160627033728 builduid: bbebe8b318bb492daf3521276efec601 revision: e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:53.514265) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:53.514767) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:53.515065) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:53.543037) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:53.543303) ========= 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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-06-27 04:44:53-- 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% 161M=0s 2016-06-27 04:44:54 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.592522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:54.158325) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:54.158618) ========= 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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.039199 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:54.217625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 04:44:54.217939) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05 --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-beta --rev e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-06-27 04:44:54,295 truncating revision to first 12 chars 2016-06-27 04:44:54,295 Setting DEBUG logging. 2016-06-27 04:44:54,295 attempt 1/10 2016-06-27 04:44:54,295 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e2f8b2e421f9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-27 04:44:55,560 unpacking tar archive at: mozilla-beta-e2f8b2e421f9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.537730 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 04:44:55.780889) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:55.781212) ========= script_repo_revision: e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:55.781593) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:55.782286) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 04:44:55.805177) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-06-27 04:44:55.805458) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 04:44:55 INFO - MultiFileLogger online at 20160627 04:44:55 in /builds/slave/test 04:44:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:44:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:44:55 INFO - {'append_to_log': False, 04:44:55 INFO - 'base_work_dir': '/builds/slave/test', 04:44:55 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:44:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:44:55 INFO - 'branch': 'Mozilla-Beta', 04:44:55 INFO - 'buildbot_json_path': 'buildprops.json', 04:44:55 INFO - 'config_files': ('talos/mac_config.py',), 04:44:55 INFO - 'default_actions': ('clobber', 04:44:55 INFO - 'read-buildbot-config', 04:44:55 INFO - 'download-and-extract', 04:44:55 INFO - 'populate-webroot', 04:44:55 INFO - 'create-virtualenv', 04:44:55 INFO - 'install', 04:44:55 INFO - 'run-tests'), 04:44:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:44:55 INFO - 'download_minidump_stackwalk': True, 04:44:55 INFO - 'download_symbols': 'ondemand', 04:44:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:44:55 INFO - 'tooltool.py': '/tools/tooltool.py', 04:44:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:44:55 INFO - '/tools/misc-python/virtualenv.py')}, 04:44:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:44:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:44:55 INFO - 'installer_path': 'installer.exe', 04:44:55 INFO - 'log_level': 'info', 04:44:55 INFO - 'log_name': 'talos', 04:44:55 INFO - 'log_to_console': True, 04:44:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:44:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:44:55 INFO - 'opt_config_files': (), 04:44:55 INFO - 'pip_index': False, 04:44:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:55 INFO - 'cmd': ('bash', 04:44:55 INFO - '-c', 04:44:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:55 INFO - 'enabled': True, 04:44:55 INFO - 'halt_on_failure': False, 04:44:55 INFO - 'name': 'check_screen_resolution'},), 04:44:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:55 INFO - 'cmd': ('bash', 04:44:55 INFO - '-c', 04:44:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:55 INFO - 'enabled': True, 04:44:55 INFO - 'halt_on_failure': False, 04:44:55 INFO - 'name': 'check_screen_resolution'},), 04:44:55 INFO - 'run_cmd_checks_enabled': True, 04:44:55 INFO - 'sps_profile': False, 04:44:55 INFO - 'sps_profile_interval': 0, 04:44:55 INFO - 'suite': 'dromaeojs', 04:44:55 INFO - 'system_bits': '32', 04:44:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:44:55 INFO - 'title': 't-yosemite-r7-0008', 04:44:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:44:55 INFO - 'use_talos_json': True, 04:44:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:44:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:44:55 INFO - 'work_dir': 'build'} 04:44:55 INFO - ##### 04:44:55 INFO - ##### Running clobber step. 04:44:55 INFO - ##### 04:44:55 INFO - Running pre-action listener: _resource_record_pre_action 04:44:55 INFO - Running main action method: clobber 04:44:55 INFO - rmtree: /builds/slave/test/build 04:44:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:44:58 INFO - Running post-action listener: _resource_record_post_action 04:44:58 INFO - ##### 04:44:58 INFO - ##### Running read-buildbot-config step. 04:44:58 INFO - ##### 04:44:58 INFO - Running pre-action listener: _resource_record_pre_action 04:44:58 INFO - Running main action method: read_buildbot_config 04:44:58 INFO - Using buildbot properties: 04:44:58 INFO - { 04:44:58 INFO - "project": "", 04:44:58 INFO - "product": "firefox", 04:44:58 INFO - "script_repo_revision": "production", 04:44:58 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 04:44:58 INFO - "repository": "", 04:44:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 04:44:58 INFO - "buildid": "20160627033728", 04:44:58 INFO - "pgo_build": "False", 04:44:58 INFO - "basedir": "/builds/slave/test", 04:44:58 INFO - "buildnumber": 7, 04:44:58 INFO - "slavename": "t-yosemite-r7-0008", 04:44:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:44:58 INFO - "platform": "yosemite_r7", 04:44:58 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 04:44:58 INFO - "branch": "mozilla-beta", 04:44:58 INFO - "repo_path": "releases/mozilla-beta", 04:44:58 INFO - "revision": "e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05", 04:44:58 INFO - "stage_platform": "macosx64", 04:44:58 INFO - "builduid": "bbebe8b318bb492daf3521276efec601", 04:44:58 INFO - "slavebuilddir": "test" 04:44:58 INFO - } 04:44:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 04:44:58 INFO - Running post-action listener: _resource_record_post_action 04:44:58 INFO - ##### 04:44:58 INFO - ##### Running download-and-extract step. 04:44:58 INFO - ##### 04:44:58 INFO - Running pre-action listener: _resource_record_pre_action 04:44:58 INFO - Running main action method: download_and_extract 04:44:58 INFO - mkdir: /builds/slave/test/build/tests 04:44:58 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')]} 04:44:58 INFO - https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:44:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 04:44:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 04:44:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 04:44:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 04:45:23 INFO - Downloaded 1165 bytes. 04:45:23 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 04:45:23 INFO - Using the following test package requirements: 04:45:23 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 04:45:23 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:45:23 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 04:45:23 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:45:23 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 04:45:23 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:45:23 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:45:23 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 04:45:23 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 04:45:23 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:45:23 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 04:45:23 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:45:23 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 04:45:23 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:45:23 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 04:45:23 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:45:23 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 04:45:23 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 04:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:23 INFO - https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:45:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:45:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:45:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 04:45:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 04:45:24 INFO - Downloaded 18344067 bytes. 04:45:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:45:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:45:27 INFO - Return code: 0 04:45:27 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 04:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:27 INFO - https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:45:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:45:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:45:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 04:45:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 04:45:33 INFO - Downloaded 18344067 bytes. 04:45:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:45:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:45:37 INFO - Return code: 0 04:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:37 INFO - https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:45:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 04:45:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 04:45:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 04:45:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 04:45:38 INFO - Downloaded 10840145 bytes. 04:45:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:45:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:45:38 INFO - Return code: 0 04:45:38 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')]} 04:45:38 INFO - https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 04:45:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:45:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:45:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 04:45:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 04:45:41 INFO - Downloaded 86225931 bytes. 04:45:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:45:41 INFO - mkdir: /builds/slave/test/properties 04:45:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:45:41 INFO - Writing to file /builds/slave/test/properties/build_url 04:45:41 INFO - Contents: 04:45:41 INFO - build_url:https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:45:42 INFO - Running post-action listener: _resource_record_post_action 04:45:42 INFO - Running post-action listener: set_extra_try_arguments 04:45:42 INFO - ##### 04:45:42 INFO - ##### Running populate-webroot step. 04:45:42 INFO - ##### 04:45:42 INFO - Running pre-action listener: _resource_record_pre_action 04:45:42 INFO - Running main action method: populate_webroot 04:45:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:45:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:45:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:45:42 INFO - '--tppagecycles', 04:45:42 INFO - '7'], 04:45:42 INFO - 'tests': ['tsvgm']}}, 04:45:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:45:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:45:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:45:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:45:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:45:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:45:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:42 INFO - 'talos_options': ['--disable-e10s'], 04:45:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:42 INFO - 'talos_options': ['--disable-e10s'], 04:45:42 INFO - 'tests': ['damp', 'tps']}, 04:45:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:42 INFO - 'tests': ['damp', 'tps']}, 04:45:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:45:42 INFO - 'tests': ['dromaeo_dom']}, 04:45:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:45:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:45:42 INFO - 'tests': ['a11yr', 04:45:42 INFO - 'ts_paint', 04:45:42 INFO - 'tpaint', 04:45:42 INFO - 'sessionrestore', 04:45:42 INFO - 'sessionrestore_no_auto_restore', 04:45:42 INFO - 'tabpaint']}, 04:45:42 INFO - 'other-e10s': {'tests': ['a11yr', 04:45:42 INFO - 'ts_paint', 04:45:42 INFO - 'tpaint', 04:45:42 INFO - 'sessionrestore', 04:45:42 INFO - 'sessionrestore_no_auto_restore', 04:45:42 INFO - 'tabpaint']}, 04:45:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:45:42 INFO - 'ts_paint', 04:45:42 INFO - 'tpaint', 04:45:42 INFO - 'sessionrestore', 04:45:42 INFO - 'sessionrestore_no_auto_restore', 04:45:42 INFO - 'tabpaint']}, 04:45:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:45:42 INFO - 'ts_paint', 04:45:42 INFO - 'tpaint', 04:45:42 INFO - 'sessionrestore', 04:45:42 INFO - 'sessionrestore_no_auto_restore', 04:45:42 INFO - 'tabpaint']}, 04:45:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:45:42 INFO - 'tests': ['a11yr', 04:45:42 INFO - 'ts_paint', 04:45:42 INFO - 'tpaint', 04:45:42 INFO - 'sessionrestore', 04:45:42 INFO - 'sessionrestore_no_auto_restore', 04:45:42 INFO - 'tabpaint']}, 04:45:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:45:42 INFO - 'tests': ['a11yr', 04:45:42 INFO - 'ts_paint', 04:45:42 INFO - 'tpaint', 04:45:42 INFO - 'sessionrestore', 04:45:42 INFO - 'sessionrestore_no_auto_restore', 04:45:42 INFO - 'tabpaint']}, 04:45:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:45:42 INFO - 'tests': ['tsvgx', 04:45:42 INFO - 'tsvgr_opacity', 04:45:42 INFO - 'tart', 04:45:42 INFO - 'tscrollx', 04:45:42 INFO - 'cart']}, 04:45:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:45:42 INFO - 'tsvgr_opacity', 04:45:42 INFO - 'tart', 04:45:42 INFO - 'tscrollx', 04:45:42 INFO - 'cart']}, 04:45:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:42 INFO - 'talos_options': ['--disable-e10s'], 04:45:42 INFO - 'tests': ['tp5o']}, 04:45:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:42 INFO - 'tests': ['tp5o']}, 04:45:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:42 INFO - 'talos_options': ['--disable-e10s', 04:45:42 INFO - '--xperf_path', 04:45:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:42 INFO - 'tests': ['tp5n']}, 04:45:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:42 INFO - 'talos_options': ['--xperf_path', 04:45:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:42 INFO - 'tests': ['tp5n']}}, 04:45:42 INFO - 'talos.zip': {'path': '', 04:45:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:45:42 INFO - Running post-action listener: _resource_record_post_action 04:45:42 INFO - ##### 04:45:42 INFO - ##### Running create-virtualenv step. 04:45:42 INFO - ##### 04:45:42 INFO - Running pre-action listener: _resource_record_pre_action 04:45:42 INFO - Running main action method: create_virtualenv 04:45:42 INFO - Creating virtualenv /builds/slave/test/build/venv 04:45:42 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 04:45:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:45:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:45:43 INFO - Using real prefix '/tools/python27' 04:45:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:45:43 INFO - Installing distribute.............................................................................................................................................................................................done. 04:45:46 INFO - Installing pip.................done. 04:45:46 INFO - Return code: 0 04:45:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 04:45: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', 'pip>=1.5'] in /builds/slave/test/build 04:45: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 pip>=1.5 04:45:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render', 04:45:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:46 INFO - 'HOME': '/Users/cltbld', 04:45:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:46 INFO - 'LOGNAME': 'cltbld', 04:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:46 INFO - 'MOZ_NO_REMOTE': '1', 04:45:46 INFO - 'NO_EM_RESTART': '1', 04:45:46 INFO - 'PAGER': '/bin/cat', 04:45:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:46 INFO - 'PWD': '/builds/slave/test', 04:45:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:46 INFO - 'SHELL': '/bin/bash', 04:45:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners', 04:45:46 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 04:45:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:46 INFO - 'USER': 'cltbld', 04:45:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:46 INFO - 'XPC_FLAGS': '0x0', 04:45:46 INFO - 'XPC_SERVICE_NAME': '0', 04:45:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:46 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 04:45:46 INFO - Cleaning up... 04:45:46 INFO - Return code: 0 04:45:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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', '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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 04:45: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', 'psutil>=0.7.1'] in /builds/slave/test/build 04:45: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 psutil>=0.7.1 04:45:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render', 04:45:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:46 INFO - 'HOME': '/Users/cltbld', 04:45:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:46 INFO - 'LOGNAME': 'cltbld', 04:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:46 INFO - 'MOZ_NO_REMOTE': '1', 04:45:46 INFO - 'NO_EM_RESTART': '1', 04:45:46 INFO - 'PAGER': '/bin/cat', 04:45:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:46 INFO - 'PWD': '/builds/slave/test', 04:45:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:46 INFO - 'SHELL': '/bin/bash', 04:45:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners', 04:45:46 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 04:45:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:46 INFO - 'USER': 'cltbld', 04:45:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:46 INFO - 'XPC_FLAGS': '0x0', 04:45:46 INFO - 'XPC_SERVICE_NAME': '0', 04:45:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:47 INFO - Downloading/unpacking psutil>=0.7.1 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:45:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:50 INFO - Installing collected packages: psutil 04:45:50 INFO - Running setup.py install for psutil 04:45:50 INFO - building 'psutil._psutil_osx' extension 04:45:50 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 04:45:50 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 04:45:50 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 04:45:50 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 04:45:51 INFO - building 'psutil._psutil_posix' extension 04:45:51 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 04:45:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:45:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:45:51 INFO - ^ 04:45:51 INFO - 1 warning generated. 04:45:51 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 04:45:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:51 INFO - Successfully installed psutil 04:45:51 INFO - Cleaning up... 04:45:51 INFO - Return code: 0 04:45:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:45:51 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')]} 04:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:51 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')]} 04:45:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:51 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 04:45:51 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 04:45:51 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 04:45:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render', 04:45:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:51 INFO - 'HOME': '/Users/cltbld', 04:45:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:51 INFO - 'LOGNAME': 'cltbld', 04:45:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:51 INFO - 'MOZ_NO_REMOTE': '1', 04:45:51 INFO - 'NO_EM_RESTART': '1', 04:45:51 INFO - 'PAGER': '/bin/cat', 04:45:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:51 INFO - 'PWD': '/builds/slave/test', 04:45:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:51 INFO - 'SHELL': '/bin/bash', 04:45:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners', 04:45:51 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 04:45:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:51 INFO - 'USER': 'cltbld', 04:45:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:51 INFO - 'XPC_FLAGS': '0x0', 04:45:51 INFO - 'XPC_SERVICE_NAME': '0', 04:45:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:45:51 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 04:45:51 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 04:45:51 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 04:45:51 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 04:45:51 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 04:45:51 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 04:45:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:45:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:45:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:45:54 INFO - Installing collected packages: mozsystemmonitor 04:45:54 INFO - Running setup.py install for mozsystemmonitor 04:45:54 INFO - Successfully installed mozsystemmonitor 04:45:54 INFO - Cleaning up... 04:45:54 INFO - Return code: 0 04:45:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:54 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 04:45:54 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 04:45:54 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 04:45:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render', 04:45:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:54 INFO - 'HOME': '/Users/cltbld', 04:45:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:54 INFO - 'LOGNAME': 'cltbld', 04:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:54 INFO - 'MOZ_NO_REMOTE': '1', 04:45:54 INFO - 'NO_EM_RESTART': '1', 04:45:54 INFO - 'PAGER': '/bin/cat', 04:45:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:54 INFO - 'PWD': '/builds/slave/test', 04:45:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:54 INFO - 'SHELL': '/bin/bash', 04:45:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners', 04:45:54 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 04:45:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:54 INFO - 'USER': 'cltbld', 04:45:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:54 INFO - 'XPC_FLAGS': '0x0', 04:45:54 INFO - 'XPC_SERVICE_NAME': '0', 04:45:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:54 INFO - Downloading/unpacking blobuploader==1.2.4 04:45:54 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 04:45:54 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 04:45:54 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 04:45:54 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 04:45:54 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 04:45:54 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 04:45:57 INFO - Downloading blobuploader-1.2.4.tar.gz 04:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:45:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:45:57 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 04:45:57 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 04:45:57 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 04:45:57 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 04:45:57 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 04:45:57 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 04:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:45:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:45:57 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 04:45:57 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 04:45:57 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 04:45:57 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 04:45:57 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 04:45:57 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 04:45:58 INFO - Downloading docopt-0.6.1.tar.gz 04:45:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:45:58 INFO - Installing collected packages: blobuploader, requests, docopt 04:45:58 INFO - Running setup.py install for blobuploader 04:45:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:45:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:45:58 INFO - Running setup.py install for requests 04:45:59 INFO - Running setup.py install for docopt 04:45:59 INFO - Successfully installed blobuploader requests docopt 04:45:59 INFO - Cleaning up... 04:45:59 INFO - Return code: 0 04:45:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:59 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 04:45:59 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 04:45:59 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 04:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render', 04:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:59 INFO - 'HOME': '/Users/cltbld', 04:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:59 INFO - 'LOGNAME': 'cltbld', 04:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:59 INFO - 'MOZ_NO_REMOTE': '1', 04:45:59 INFO - 'NO_EM_RESTART': '1', 04:45:59 INFO - 'PAGER': '/bin/cat', 04:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:59 INFO - 'PWD': '/builds/slave/test', 04:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:59 INFO - 'SHELL': '/bin/bash', 04:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners', 04:45:59 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 04:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:59 INFO - 'USER': 'cltbld', 04:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:59 INFO - 'XPC_FLAGS': '0x0', 04:45:59 INFO - 'XPC_SERVICE_NAME': '0', 04:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-vfTUWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-mV7dol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-zTF7D9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-V7fSAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-iFee7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-_MMZA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Y6J3_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-rh3LxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:00 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-yXKutI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-fMnmJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-24HVlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-AVuGQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-eHSpjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-g_eQaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-OqWjht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-8RiY_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:01 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-yL6NvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:46:01 INFO - Running setup.py install for manifestparser 04:46:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:46:01 INFO - Running setup.py install for mozcrash 04:46:02 INFO - Running setup.py install for mozdebug 04:46:02 INFO - Running setup.py install for mozdevice 04:46:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:46:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:46:02 INFO - Running setup.py install for mozfile 04:46:02 INFO - Running setup.py install for mozhttpd 04:46:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:46:02 INFO - Running setup.py install for mozinfo 04:46:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:46:02 INFO - Running setup.py install for mozInstall 04:46:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:46:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:46:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:46:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:46:03 INFO - Running setup.py install for mozleak 04:46:03 INFO - Running setup.py install for mozlog 04:46:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:46:03 INFO - Running setup.py install for moznetwork 04:46:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:46:03 INFO - Running setup.py install for mozprocess 04:46:03 INFO - Running setup.py install for mozprofile 04:46:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:46:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:46:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:46:03 INFO - Running setup.py install for mozrunner 04:46:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:46:03 INFO - Running setup.py install for mozscreenshot 04:46:04 INFO - Running setup.py install for moztest 04:46:04 INFO - Running setup.py install for mozversion 04:46:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:46:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:46:04 INFO - Cleaning up... 04:46:04 INFO - Return code: 0 04:46:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:04 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 04:46:04 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 04:46:04 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 04:46:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render', 04:46:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:04 INFO - 'HOME': '/Users/cltbld', 04:46:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:04 INFO - 'LOGNAME': 'cltbld', 04:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:04 INFO - 'MOZ_NO_REMOTE': '1', 04:46:04 INFO - 'NO_EM_RESTART': '1', 04:46:04 INFO - 'PAGER': '/bin/cat', 04:46:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:04 INFO - 'PWD': '/builds/slave/test', 04:46:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:04 INFO - 'SHELL': '/bin/bash', 04:46:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners', 04:46:04 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 04:46:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:04 INFO - 'USER': 'cltbld', 04:46:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:04 INFO - 'XPC_FLAGS': '0x0', 04:46:04 INFO - 'XPC_SERVICE_NAME': '0', 04:46:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:46:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-y69qnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:46:04 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)) 04:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:46:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-N9QHB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:46:04 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)) 04:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:46:04 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-NGt1jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:46:04 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)) 04:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-TZjEOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:46:05 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)) 04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-lygHrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:46:05 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)) 04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-oX6KF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:46:05 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)) 04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-OhmmxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46:05 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)) 04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-6ylf4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46:05 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)) 04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-FqUg0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46:05 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)) 04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-4bv_Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46:05 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)) 04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-_jwYY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46:05 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)) 04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-4Jcykb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46:05 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)) 04:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:05 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-zFdnnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:06 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)) 04:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:06 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-yQzgd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:06 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)) 04:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:06 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-zKmkEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:06 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)) 04:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:06 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-LidQIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46:06 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)) 04:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:06 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-YPjU5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46:06 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)) 04:46:06 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)) 04:46:06 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)) 04:46:06 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)) 04:46:06 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)) 04:46:06 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)) 04:46:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:08 INFO - Downloading blessings-1.6.tar.gz 04:46:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:46:08 INFO - Installing collected packages: blessings 04:46:08 INFO - Running setup.py install for blessings 04:46:09 INFO - Successfully installed blessings 04:46:09 INFO - Cleaning up... 04:46:09 INFO - Return code: 0 04:46:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:46:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:09 INFO - Reading from file tmpfile_stdout 04:46:09 INFO - Current package versions: 04:46:09 INFO - blessings == 1.6 04:46:09 INFO - blobuploader == 1.2.4 04:46:09 INFO - docopt == 0.6.1 04:46:09 INFO - manifestparser == 1.1 04:46:09 INFO - mozInstall == 1.12 04:46:09 INFO - mozcrash == 0.17 04:46:09 INFO - mozdebug == 0.1 04:46:09 INFO - mozdevice == 0.48 04:46:09 INFO - mozfile == 1.2 04:46:09 INFO - mozhttpd == 0.7 04:46:09 INFO - mozinfo == 0.9 04:46:09 INFO - mozleak == 0.1 04:46:09 INFO - mozlog == 3.1 04:46:09 INFO - moznetwork == 0.27 04:46:09 INFO - mozprocess == 0.22 04:46:09 INFO - mozprofile == 0.28 04:46:09 INFO - mozrunner == 6.11 04:46:09 INFO - mozscreenshot == 0.1 04:46:09 INFO - mozsystemmonitor == 0.0 04:46:09 INFO - moztest == 0.7 04:46:09 INFO - mozversion == 1.4 04:46:09 INFO - psutil == 3.1.1 04:46:09 INFO - requests == 1.2.3 04:46:09 INFO - wsgiref == 0.1.2 04:46:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46:09 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')]} 04:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:09 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')]} 04:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:09 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 04:46:09 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 04:46:09 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 04:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render', 04:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:09 INFO - 'HOME': '/Users/cltbld', 04:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:09 INFO - 'LOGNAME': 'cltbld', 04:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:09 INFO - 'MOZ_NO_REMOTE': '1', 04:46:09 INFO - 'NO_EM_RESTART': '1', 04:46:09 INFO - 'PAGER': '/bin/cat', 04:46:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:09 INFO - 'PWD': '/builds/slave/test', 04:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:09 INFO - 'SHELL': '/bin/bash', 04:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners', 04:46:09 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 04:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:09 INFO - 'USER': 'cltbld', 04:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:09 INFO - 'XPC_FLAGS': '0x0', 04:46:09 INFO - 'XPC_SERVICE_NAME': '0', 04:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:09 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)) 04:46:09 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)) 04:46:09 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)) 04:46:09 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)) 04:46:09 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)) 04:46:09 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)) 04:46:09 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)) 04:46:09 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)) 04:46:09 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)) 04:46:09 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)) 04:46:09 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)) 04:46:09 INFO - Cleaning up... 04:46:09 INFO - Return code: 0 04:46:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:46:09 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')]} 04:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:09 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')]} 04:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:09 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 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 04:46:09 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 04:46:09 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 04:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render', 04:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:09 INFO - 'HOME': '/Users/cltbld', 04:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:09 INFO - 'LOGNAME': 'cltbld', 04:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:09 INFO - 'MOZ_NO_REMOTE': '1', 04:46:09 INFO - 'NO_EM_RESTART': '1', 04:46:09 INFO - 'PAGER': '/bin/cat', 04:46:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:09 INFO - 'PWD': '/builds/slave/test', 04:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:09 INFO - 'SHELL': '/bin/bash', 04:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners', 04:46:09 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 04:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:09 INFO - 'USER': 'cltbld', 04:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:09 INFO - 'XPC_FLAGS': '0x0', 04:46:09 INFO - 'XPC_SERVICE_NAME': '0', 04:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:10 INFO - Downloading/unpacking jsonschema 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:46:12 INFO - Downloading/unpacking functools32 (from jsonschema) 04:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:12 INFO - Downloading functools32-3.2.3-2.tar.gz 04:46:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:46:12 INFO - warning: no files found matching '*.txt' 04:46:12 INFO - no previously-included directories found matching 'build' 04:46:12 INFO - no previously-included directories found matching 'dist' 04:46:12 INFO - no previously-included directories found matching '.git*' 04:46:12 INFO - Installing collected packages: jsonschema, functools32 04:46:12 INFO - Running setup.py install for functools32 04:46:13 INFO - warning: no files found matching '*.txt' 04:46:13 INFO - no previously-included directories found matching 'build' 04:46:13 INFO - no previously-included directories found matching 'dist' 04:46:13 INFO - no previously-included directories found matching '.git*' 04:46:13 INFO - Successfully installed jsonschema functools32 04:46:13 INFO - Cleaning up... 04:46:13 INFO - Return code: 0 04:46:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:46:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:46:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb64a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb74420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9c84652c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, '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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 04:46:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:46:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:46:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render', 04:46:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:13 INFO - 'HOME': '/Users/cltbld', 04:46:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:13 INFO - 'LOGNAME': 'cltbld', 04:46:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:13 INFO - 'MOZ_NO_REMOTE': '1', 04:46:13 INFO - 'NO_EM_RESTART': '1', 04:46:13 INFO - 'PAGER': '/bin/cat', 04:46:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:13 INFO - 'PWD': '/builds/slave/test', 04:46:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:13 INFO - 'SHELL': '/bin/bash', 04:46:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners', 04:46:13 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 04:46:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:13 INFO - 'USER': 'cltbld', 04:46:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:13 INFO - 'XPC_FLAGS': '0x0', 04:46:13 INFO - 'XPC_SERVICE_NAME': '0', 04:46:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:13 INFO - Downloading/unpacking flake8 04:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:46:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:46:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:46:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:46:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:46:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:46:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:46:17 INFO - no previously-included directories found matching 'docs/_build' 04:46:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:46:17 INFO - Running setup.py install for pep8 04:46:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:46:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:46:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:46:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:46:17 INFO - no previously-included directories found matching 'docs/_build' 04:46:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:46:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:46:17 INFO - Cleaning up... 04:46:17 INFO - Return code: 0 04:46:17 INFO - Running post-action listener: _resource_record_post_action 04:46:17 INFO - Running post-action listener: _start_resource_monitoring 04:46:17 INFO - Starting resource monitoring. 04:46:17 INFO - ##### 04:46:17 INFO - ##### Running install step. 04:46:17 INFO - ##### 04:46:17 INFO - Running pre-action listener: _resource_record_pre_action 04:46:17 INFO - Running main action method: install 04:46:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:17 INFO - Reading from file tmpfile_stdout 04:46:17 INFO - Detecting whether we're running mozinstall >=1.0... 04:46:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:46:17 INFO - Reading from file tmpfile_stdout 04:46:17 INFO - Output received: 04:46:17 INFO - Usage: mozinstall [options] installer 04:46:17 INFO - Options: 04:46:17 INFO - -h, --help show this help message and exit 04:46:17 INFO - -d DEST, --destination=DEST 04:46:17 INFO - Directory to install application into. [default: 04:46:17 INFO - "/builds/slave/test"] 04:46:17 INFO - --app=APP Application being installed. [default: firefox] 04:46:17 INFO - mkdir: /builds/slave/test/build/application 04:46:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:46:39 INFO - Reading from file tmpfile_stdout 04:46:39 INFO - Output received: 04:46:39 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:46:39 INFO - Running post-action listener: _resource_record_post_action 04:46:39 INFO - ##### 04:46:39 INFO - ##### Running run-tests step. 04:46:39 INFO - ##### 04:46:39 INFO - Running pre-action listener: _resource_record_pre_action 04:46:39 INFO - Running main action method: run_tests 04:46:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:46:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:46:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:46:39 INFO - 2016-06-27 04:46:39.578 screenresolution[1648:8232] starting screenresolution argv=screenresolution get 04:46:39 INFO - 2016-06-27 04:46:39.580 screenresolution[1648:8232] Display 0: 1600x1200x32@60 04:46:39 INFO - 2016-06-27 04:46:39.586 screenresolution[1649:8234] starting screenresolution argv=screenresolution list 04:46:39 INFO - Available Modes on Display 0 04:46:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:46:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:46:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:46:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:46:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:46:39 INFO - Intel Iris: 04:46:39 INFO - Chipset Model: Intel Iris 04:46:39 INFO - Type: GPU 04:46:39 INFO - Bus: Built-In 04:46:39 INFO - VRAM (Dynamic, Max): 1536 MB 04:46:39 INFO - Vendor: Intel (0x8086) 04:46:39 INFO - Device ID: 0x0a2e 04:46:39 INFO - Revision ID: 0x0009 04:46:39 INFO - Displays: 04:46:39 INFO - Display: 04:46:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:46:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:46:39 INFO - Display Serial Number: 1600x1200 60 04:46:39 INFO - Main Display: Yes 04:46:39 INFO - Mirror: Off 04:46:39 INFO - Online: Yes 04:46:39 INFO - Rotation: Supported 04:46:39 INFO - Return code: 0 04:46:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:46:39 INFO - Python 2.7.3 04:46:39 INFO - Return code: 0 04:46:39 INFO - grabbing minidump binary from tooltool 04:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:46:39 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 0x10fa03e00>, '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 0x10f86cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9feb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:46:39 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 04:46:39 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 04:46:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:46:39 INFO - Return code: 0 04:46:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:46:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:46:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:46:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:46:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:46:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:46:41 INFO - Return code: 0 04:46:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--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 04:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0008 --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 04:46:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ILS8nAVdl1/Render', 04:46:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:41 INFO - 'HOME': '/Users/cltbld', 04:46:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:41 INFO - 'LOGNAME': 'cltbld', 04:46:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:46:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:41 INFO - 'MOZ_NO_REMOTE': '1', 04:46:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:46:41 INFO - 'NO_EM_RESTART': '1', 04:46:41 INFO - 'PAGER': '/bin/cat', 04:46:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:41 INFO - 'PWD': '/builds/slave/test', 04:46:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:46:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:41 INFO - 'SHELL': '/bin/bash', 04:46:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xa2o4KSJuj/Listeners', 04:46:41 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 04:46:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:41 INFO - 'USER': 'cltbld', 04:46:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:41 INFO - 'XPC_FLAGS': '0x0', 04:46:41 INFO - 'XPC_SERVICE_NAME': '0', 04:46:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--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 04:46:41 INFO - mozversion application_buildid: 20160627033728 04:46:41 INFO - mozversion application_changeset: e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05 04:46:41 INFO - mozversion application_display_name: Firefox 04:46:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:46:41 INFO - mozversion application_name: Firefox 04:46:41 INFO - mozversion application_remotingname: firefox 04:46:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:46:41 INFO - mozversion application_vendor: Mozilla 04:46:41 INFO - mozversion application_version: 48.0 04:46:41 INFO - mozversion platform_buildid: 20160627033728 04:46:41 INFO - mozversion platform_changeset: e2f8b2e421f954c7f3b74f9f7bd7a982aed94d05 04:46:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:46:41 INFO - mozversion platform_version: 48.0 04:46:41 INFO - using testdate: 1467028001 04:46:41 INFO - actual date: 1467028001 04:46:41 INFO - starting webserver on 'localhost:49277' 04:46:41 INFO - SUITE-START | Running 2 tests 04:46:41 INFO - TEST-START | dromaeo_css 04:46:41 INFO - operating with platform_type : mac_ 04:46:41 INFO - Initialising browser for dromaeo_css test... 04:46:41 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpBhE3xd/profile http://localhost:49277/getInfo.html) 04:46:43 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 04:46:43 INFO - PROCESS | 1667 | colorDepth:24 04:46:43 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 04:46:43 INFO - PROCESS | 1667 | __metrics 04:46:43 INFO - PROCESS | 1667 | 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" 04:46:43 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:46:44 INFO - TEST-INFO | 1667: exit 0 04:46:44 INFO - Browser initialized. 04:46:44 INFO - Running cycle 1/1 for dromaeo_css test... 04:46:44 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpBhE3xd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:55 INFO - PROCESS | 1671 | RSS: Main: 161427456 04:46:55 INFO - PROCESS | 1671 | 04:47:47 INFO - PROCESS | 1671 | 1467028067286 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] 04:49:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 04:49:58 INFO - PROCESS | 1671 | RSS: Main: 254578688 04:49:58 INFO - PROCESS | 1671 | 04:53:01 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 04:53:01 INFO - PROCESS | 1671 | RSS: Main: 306917376 04:53:01 INFO - PROCESS | 1671 | 04:56:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 04:56:04 INFO - PROCESS | 1671 | RSS: Main: 310345728 04:56:04 INFO - PROCESS | 1671 | 04:59:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 04:59:07 INFO - PROCESS | 1671 | RSS: Main: 304783360 04:59:07 INFO - PROCESS | 1671 | 05:02:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 05:02:10 INFO - PROCESS | 1671 | RSS: Main: 270704640 05:02:10 INFO - PROCESS | 1671 | 05:05:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 05:05:13 INFO - PROCESS | 1671 | RSS: Main: 312348672 05:05:13 INFO - PROCESS | 1671 | 05:05:13 INFO - PROCESS | 1671 | __start_tp_report 05:05:13 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 05:05:13 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 05:05:13 INFO - PROCESS | 1671 | |i|pagename|runs| 05:05:13 INFO - PROCESS | 1671 | |0;dojo.html;3345.330933813237;3628.546431696038;3633.5821380541247;3642.4900513928055;3712.4988126602902;5930.792422265222;5944.851378715534;5954.880902381949;5965.88068238635;5996.610220335675;5946.791862284821;5987.221661184051;6000.33996260411;6012.2184116064855;6019.097135429688;5286.471352864722;5351.357837059559;5383.515483606475;5438.401775804658;5473.890522189554;5422.728863556826;5460.645058071213;5465.234867118606;5480.150576660604;5481.205225242337;5403.189521571776;5440.9934162180125;5448.210009548613;5477.726113694319;5492.752826122814;4674.994876101655;5447.645903016291;5460.180972854104;5463.535599474009;5487.176923461473;5300.681959082467;5318.494742999409;5322.361316641989;5400.270963419927;5410.621256512046;5834.008218602848;5834.2998840139035;5861.736221870026;5879.235699359098;5915.526757859361;6289.559730818844;6300.275468321156;6337.651429171394;6340.112384266206;6341;5555.500004999529;6201.720922558496;6224.439800417938;6412.198475190601;6421.614703117782;6077.270727512682;6083.756649734005;6114.9694251529345;6126.111713801474;6171.25944886612;6502.804915852532;6514.739410423625;6518.934810651833;6525.738970441129;6526.804195874131;6194.752209911643;6221.5333849961435;6272.278687950853;6296.275928268216;6318.8420289492215;6381.3937675920715;6382.20222472191;6394.712237949303;6395.232572091379;6400.871982560323;6233.407826256498;6278.623282603058;6295.087212354183;6298.968505157445;6323.588966717149;6213.751449942042;6250.562460627803;6265.937340626627;6320.652364120017;6336.841578960563;5040.974795126001;5067.442581316052;5077.568406685473;5092.28707980883;5112.258722485219;4611.262198048296;4612.238980568236;4615.215413379734;4623.9075218495445;4636.188666983265;5876.236089308362;5974.731137098841;6014.609050411709;6111.113888486234;6115.082737589397;6345.524085693592;6386.936130638727;6402.071699603532;6404.679766011681;6420.839479013062;6031.125486804476;6051.485623722033;6074.210352654127;6076.118962750376;6107.969460152671;5416.7020813855615;5540.335159780852;5556.16657501378;5590.636608620427;5633.859153521113;6581.144451221407;6606.174228221473;6631.20425548918;6648.767293144717;6656.334366563305;6216.937830621636;6369.363063693593;6385.425311721968;6390.616563006234;6413.8717225656155;6518.706658200487;6616.172978377703;6618.205815302194;6632.336766323329;6657.567258128206;6305.054241863573;6319.715612797527;6339.651319177489;6352;6379.011253255753;6242.438180563771;6280.371962803683;6342.143810585512;6350.872982540416;6426.646534440651;6229.657368844757;6254.749810007731;6274.686265686789;6279.497640188682;6293.811185664437;5306.548943339705;5401.432849550741;5606.523445507178;6072.271327440735;6260.186175797299;6083.969580152071;6111.663858487648;6165.475934545614;6177.227846519185;6186.505079593531;6127.758165267708;6157.353477884758;6218.782342618168;6219.813405597839;6245.18812554365;6060.454559089703;6128.325884152829;6128.66292353907;6128.693565321805;6130.9577371846 05:05:13 INFO - PROCESS | 1671 | |1;ext.html;516.5428595692812;523.8585581892889;608.8812681527104;611.6788685939878;641.0640464921215;32957.670423295756;33603.327933441375;33604.327913441746;33616.32767344652;33630.32739345211;39299.21401571967;43141.35287970683;43145.78427107868;43163.115319621225;43189.35215971763;9697.903020969807;9897.50512474377;9909.950450247774;9923.851142232872;9950.552225149886;9164.8625270621;9367.531623418836;9679.225661947028;9807.558659860324;11994.040476761882;11885.227460215101;11973.880261197408;11980.98161656256;11987.40062996851;11994.220375675553;11773.352465614387;11853.703657408547;11873.990710789592;11875.82186267211;11889.821652675217;11693.883061169365;11790.056795456429;11791.056715462742;11799.292042477393;11804.173707840455;41305.38041929373;41668.37497437541;41758.37362439566;43783;44054;3487.668671476206;3537.6970579000103;3558.857645694169;3724.981375093107;3998.8000599969887;27803;28455.857720711472;28648.570271445944;28706.282342941384;28877.278068048257;2695.649565556465;2716.5925111233278;2724.5368287391193;2740.2190375742816;2742.012875364867;407885;413800.9309953461;415267;415409;416173;67236.66381668061;67574;67701;67715;67869;71916.92124618134;75977.86033209506;75992.62003689946;76008;76012.61993689997;18034.917904925747;18258.726119108225;18322.175502102436;18324.90837545804;18326.992015439013;12433.191842530206;13278.601641950758;14702.852971470362;14805.777913331309;14819;17924.193411296525;18106;18119.547011324823;18139.09304534768;20805.791942080687;7886.960565197137;7898.921010789819;7919.802004949807;7925.286724194735;7969.800754981172;1004.2468148888333;1056.3186744549876;1056.5562463765236;1077.9029887310023;1103.624767579027;31740.206494837814;31920;32069.67930320667;32130.83934580312;32270.83864580662;1080.438172150493;1105;1154.3708678769922;1167.795635763738;1175.535663412947;16299.674006519803;16325.59186020359;16383.918080409521;16556.172191390382;16648.833511664972;649366;736437;738718;739265;741428;51167.744161278955;63895.68052159803;64519.67740161363;64637;64644;1529.755239161751;1530.3266562712372;1543.398074750848;1547.257316488092;1549.527394144774;29213.85393073021;31317.23976561631;32862;33025.50461743124;33386;58124.709376454535;58512.707436462544;58614.70692646509;58647.4135258659;58702.412975869694;491715;507085;507142;507403;573481;26264.4747105053;26863.86568067147;29034.709652903202;29912;30194.24514387158;41192;41202.79398602988;45424.77287613541;45571.316430254235;45577.860553486425;36316.91049268526;37291.06772330713;37419.69031083899;37432.625673742914;38124.04689882775;38202.617973819906;38725.80637096796;38963.415548767356;39448.605513944494;39605.00987475336;39235.822925312284;39432.6056739429;39489.802550987064;39738.801305993285;40151.59848401479;33092.17269568184;33360.583044734194;34527.65472345244;35041.12397190091;35166.82416587901;25780.742192578586;26045.739542604333;26148.477030459664;26278.94884204687;26458.86770566135 05:05:13 INFO - PROCESS | 1671 | |2;jquery.html;400.9478767760191;418.82409388057033;422.7379025004497;423.86648205815163;432.5307041859582;16382.344706211752;20853.663413853785;21269.787302126955;21634.675479867816;21653.67519487209;21181.470463238387;21433.785662143375;22279.77720222797;22570.887145564247;22575.887120564374;4700.882477938053;6104.08438734189;6151.969240153793;6158.56890017699;6169.753209871622;4432.578905004036;5772.682502462363;5975.671338076404;6682.198136223636;6847.520673552854;6767.796966091025;6784.321567843201;6786.253512113663;6792.117024786796;6799.286074962132;6416.550841441078;6669.032990216391;6671.966640166816;6676.098726671886;6684.398404143651;5537.473939975695;5837.474627283523;5857.619254748428;5864.06175011996;5866.178734977107;13481.730365392637;13674.93162534191;13715.794263086062;13741.24423156726;14604.70790584193;2196.8242540596716;2308.3998160478227;2369.6563998220163;2384.225126833786;2510.1590967025886;9854.753631159278;10193.23550733698;11236.606718764886;11298.209125361229;14677.77983330251;1807.7921039080543;1828.9725654115198;1850.0009994603033;1857.1735577667944;1903.381401044666;107816.99999999921;109592;119872;120281;121280.57438851261;11257.099432045417;11554.94222528882;11719.238249513925;12021;12144.453499592539;36036.819815901275;36558.90323290305;36972.26055478875;37006.29937006278;37010.629893701254;11318.434078296052;11437.885621143681;11583.826242606368;11601.419929003516;11914.880851191378;8550.957245213734;9607.471589062528;9745.220382369393;10000.999900009941;10167.135793457639;10105.595776169019;10182.796344073226;10213.642522511676;10258.640947566948;10268.127209187154;3332.8833490827706;3968.5634580196156;3975.6819454443576;4009.4363198352667;4125.443065186221;913.6299798581585;969.5152423788106;978.1196922769423;987.1411871671692;995.7162208770466;20040.298589549588;21324.253651122137;21328.573428531654;21397.46506337354;21706.78293217079;900.9009009009004;991.4844280973854;1004.5780772075746;1018.6689325969089;1116.2911551164907;11097.667069987914;11325.490352934137;11364.715882103013;11431;11446;439520;639102.0224494425;650452;654766;663968;92289.53855230815;111371;127103;127690;128872.35563822309;465.6717014504766;488.8899997500534;489.40781654198105;489.6009752052066;490.95581397673664;8313.875291870747;17343.132843358035;18818.059097045367;19392;19561.119749611586;40082.799586001885;47501.287480687126;51503.74248128735;51529.742351288005;53217.20174197313;75695;77190.6140469294;80113;82447.93880153044;83147.16852831394;10252;11205.887941120485;12092;12142.300077989265;12501.749965000467;13155.802662960255;13341.599752007456;13545.051846371054;13701;13862.514811981533;10300.587976480856;11420.714482138013;11481.942590286995;11641.76716465649;11740.236884602473;11344.319340839576;11444.084473241954;11449.484773185393;11828.17202795796;11873.643790686294;6034.939650603438;8276.503409795432;11656.426382438261;11859.110566707704;12075.637730868088;12221.877781222074;12277.631671049883;12486.438110284877;12667.239965602093;12738;11144.387058711525;11731.530738770354;11761.647150585497;11819.586314479297;12108.273503589813 05:05:13 INFO - PROCESS | 1671 | |3;mootools.html;1488.4641529049534;1617.5632579203614;1631.9020858748472;1887.311131437025;2006.5284658105352;8348.499090054602;9014.143656352644;9168.174864262215;9172.357934944557;9183.908160918381;8815.162559556848;9052.366334356597;9064.682736104238;9075.273978081754;9087.091290870909;2564.358910272432;2591.753783390575;2592.8833202505934;2593.9351516212055;2594.675665541807;2327.278543651465;2459.004103338142;2503.912363067293;2574.034736973635;2588.3011586871535;2548.808839337057;2575.626534152556;2597.571400718879;2600.9349766255805;2604.5572252717084;2281.6577513372963;2455.754424557539;2573.45957348956;2584;2588.8576128312934;2535.0620270500035;2574.1505303249883;2581.0450133450554;2583.3799888026924;2587.45663410684;8928;9189.081091890825;9252.306077044248;9266.934302555157;9281.860772088425;5564.415736347666;5641.464060914206;5661.46216109469;5682.943170568283;5692.658440493584;3601.927961440783;3692.9815350923072;3728.3475391806323;3730.570984336809;3766.2090960898245;1028.7325295423243;1060.6075751971744;1065.6802959112235;1065.8241390170613;1067.871855377352;12384.009279257638;12691.809622855664;12696.1747486414;12715.173513721578;12725.745485090247;5027.874303142377;5034.647574669738;5133.512316329943;5176.767045482962;5196.4803519648485;10930.070943969851;10931.289466184831;10934.015938565408;10938;10955.17836162291;5899.675517846474;5906.084556893688;5908.970455147697;5917.289925208916;5930.199423077829;4289.249381358312;4658.464276608166;4732.5977291929885;5262.657927234794;5306.602004849575;4222.303319952235;4249.702520823513;4335.046289816235;4354.586314300136;4370.847020354272;1378.9724205515834;1418.581518452059;1419.6592817723879;1430.799688043654;1435.4904009076627;896.1038961038961;932.2355668352022;933.2906990687127;950;984.5815528400401;6001.669908155006;6053.636781793107;6074.878502429927;6175.320714721377;6220;491.55268705477846;492.65514140101357;493.7629937629958;501.51353187408154;501.9548240658285;3328.5340052392685;3940.231654827281;4079.63283304504;4094.9869128254477;4116.773577453209;10797.08224800885;11135.220534562503;11262.04272636835;11275.887241127484;11282.210245282751;11028.117750579935;11046.33721976684;11133.33200007986;11139.944300278446;11163.939425754541;7100.5029647925185;8648.827023459495;8649.740507784776;8665.090165532529;8666.739997800076;5570.749316280858;5833.766649333979;5851.268591426072;5884.146798714248;5956.8808623828145;9895.455749933823;9945.602175912882;10070.949645251727;10091;10104.646337378157;9927.1375464684;10067.798644027225;10155.949220253851;10389.532471038972;10454.790904181722;6879.071325371211;7057.823554411181;7432.665530051053;7764.767056988299;7840.490368126054;7728.497647653109;7991.4006449515355;8501.19238672325;8511.74464766058;8572.314214862672;7455;7599.278068583476;7646.9617651911385;7657.846843063219;7721.845563088819;7421.8144546386775;7477.551346919202;7482.551046937201;7598.772036838904;7672.769816905502;6789.524733268624;6871.3128687132885;6881.071055407656;6922.79231623032;6941.062956500808;4840.152973229741;4842.128416884994;4913.70517768935;5027.698338099725;5039.319691841554;5006.974965125151;5034.874128146826;5065.746712664426;5131.10205714006;5299.602529810202 05:05:13 INFO - PROCESS | 1671 | |4;prototype.html;336.15624781797686;343.54651859545396;349.26305495404694;352.96999755020806;356.5917025006369;6661.367170118831;6661.833454163642;6674.532782705212;6690.966545167267;6717.932820671792;6620.668966551665;6638.900416493744;6645.966770166143;6655.935050391939;6693.866122677544;4722.622190224791;4805.303231031498;4858.149823780842;4912.0175964807;4925.891674373255;4850.660453768238;4900.607951363901;4918.950810491885;4930.211166213407;4937.65436419449;4849.224124140138;4868.975655121737;4869.245266983623;4925.138100832358;4925.310456536088;4701.365315682373;4790.449098353699;4909.509049095098;4935.753212339387;4979.751012449363;4230.978845105755;4241.1093670329265;4258.382534532507;4258.893527661802;4268.9786551067045;6403.775867844603;6433.517486188554;6447.226332840042;6451.774187903401;6459.35406459355;5828.504577110951;5834.095715164156;5836.270466191726;5842.73707683151;5852.531797456193;5728.68492232927;7089.971954066683;7106;7116.395106415961;7133.144022717255;4441.555844415565;4580.564846339593;4586.816527338902;4587.587117159472;4599.103174880899;7165.140183178052;7488.288612581796;7491.438142139361;7500.099988001475;7511.774646760551;4691.358024691331;4693.155232058262;4699;4707.31743897133;4707.505711900201;4750.097481478508;4755.476897541268;4761.119192949315;4768.403949506312;4772.260299653559;5170.1986192140275;5221.973890130525;5693.658380497101;5699.116636921284;5701.144828275709;6838.589684618939;7067.22260551339;7091.6454177290925;7186.209516953131;7207.747728829466;1637.8853480256269;1647.1928754910248;1660.65126323471;1662.3683000459748;1668.7079761041769;996.5366104761321;1043.0716662170673;1043.9530221140067;1061.994690026545;1070.352436775755;6622.106015687917;6745.460363170934;6777;6777.864442711217;6789.660516974231;7445.813854653568;7448.180700122982;7451.701931922771;7454.515456495311;7471.626418679044;6738.079647300496;6740.427063699641;6741.191057073084;6747.662616869137;6777.932220677829;3870.0905287257474;3903.4535165076345;3913.2369188008634;3922.764634121962;3925.725199236083;7489.737859174903;7719;7733.729319473791;7748.651310691032;7748.883766743504;7645.923540764632;7729.729459468892;7731.729389471341;7735.845283094307;7737.92262077372;7810.29707326332;7815.29662330393;7815.960920195363;7819.843603127905;7825.7652270430835;6937.549059311129;7167.462440316893;7399.8150046249275;7444.032275804111;7451.8882216765705;7265.418766498773;7333.6333183341685;7335.523190992569;7336.449766267445;7340.926590734024;7785.688572457038;7789.298963093349;7792;7799.220077992155;7813.609319533887;4247.808848601882;4499.302608095751;4676.92984605238;4749.976250118727;4755.500672429345;7119;7367.300106489874;7431.40548756109;7433.293837085468;7437.181909990005;7151.53515021522;7164.104486939133;7175.677094530654;7190.245024272585;7198.820029499305;7135.179454362607;7167.8566428672175;7181.35367816899;7187.137543494814;7195.13658361;6981.685824137824;7002.75490357835;7004.334588214111;7020.087388639444;7022.367986881205;6261.436470717657;6936;7066.152061752417;7157.069580954442;7170.892436613351;6719.529632925648;6719.865602687821;6899.724011039502;6955.826104347432;7013.509054366145 05:05:13 INFO - PROCESS | 1671 | |5;yui.html;770.6416516319914;779.244133190805;785.7328508307174;791.8930944322515;793.5714714054411;15108.622284442894;15489.14809685467;15498.845011549882;15526.767098493503;15565;13129.277889716062;14247.145171289703;14388.424463021493;14401.567952961428;14429.062110962781;949.9667511637094;952.4237836109158;966.5457235099492;967.9516024198762;968.239931653652;801.53110430398;961.6105477281712;962.8988956159608;963.8120566489538;965.584798536629;1020.34187948773;1020.6886899495651;1020.9943205942178;1024.2011231239644;1026.7073883943078;1014.9951547967532;1015.0864222200005;1015.9796804063877;1016.2784423059636;1016.7712264740449;754.8678981178272;754.9547027178332;755.6335177438938;762.8474305138939;765.7128576783706;13083.41124649393;13133.212007279593;13144.60566183016;13187.208767473887;15288.847111528854;12319;12348.320842353667;12393.256404615662;12587.24476531411;13533.255670938095;9974.152197063258;10033.397996120184;10185.185185185168;10398.168146548258;10581.941805819433;3484.61669216386;3526.3828829954655;3542.574891013069;3574.410222313315;3663.9633603663888;73068.63465682691;78031;80207;82884.17115828824;83307.5834620823;9609.231261499202;9676.371035882787;9787;9952.552135153936;10305.175585953106;21604.56790864174;21703.34889953304;21757.67363489549;21763.67354489684;21807.67288490674;7555.206703296075;7567.545947243183;7708.653110609924;7712.614369281513;7728.343090837229;4125.814338354781;4165.981963678348;4194.454720886265;4202.789860506962;4253.234417804825;3838.9424158637644;3880.5537363203593;3894.065424298148;3894.6689531389584;3905.3946638271113;687.3916583823352;695.5478938689891;697.0554898113085;711.7259854955879;724.72460465024;540.1627477409999;575.323994306688;587.0020964360604;603.6076550242377;613.515322894917;8352.206540378655;8356.973468589582;8367.456115352483;8377.45546539473;8463.576821158917;566.4279078131117;582.205289779448;599.3736545310161;617.6973283091343;635.5455849067938;3814.6948244140403;3905;3956.208758248304;3962.782046987386;3990.461287726178;89185;89705.55147224222;90239;91144;91331.54334228286;63692.726145477754;64130.717385652955;66799.33200668028;74443;75425.6228718853;67160.6641966787;69059;69188.6540567304;69696.6515167431;69906.9513957291;8315.667373305;8557.400981931454;8603.52680602573;8606.956965215133;8850.690225842065;45334.63996080123;46168.61494155046;47706.522934771594;49613.50386496089;50326.99346013132;60763;60930.08604871019;62831.37168628438;65840.6707966457;66257.00614490882;11217.55129794799;11334.60328888485;11377;11727.179097463095;11773.352465614473;12091.939540302243;12255.080868935043;12265.26408415498;12448;12544.059195560187;11570.363630000431;11803.527858885549;11977.760444791229;12020.0383969284;12121.27272363661;11533.423328833693;11752.588659397223;11779.293242405483;12059.21615095016;12344.938275308567;11333.320000799979;11455.369954652575;11540.134489913458;11629.53481860716;12472.937635311766;8905.154010369004;9136.862947055666;9159.084091590788;9369;9612.086851749073;9860;9916.900830991597;9941;9948.303618746619;9950.502474876372 05:05:13 INFO - PROCESS | 1671 | __end_tp_report 05:05:13 INFO - PROCESS | 1671 | __start_cc_report 05:05:13 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1100 05:05:13 INFO - PROCESS | 1671 | __end_cc_report 05:05:13 INFO - PROCESS | 1671 | __startTimestamp1467029113571__endTimestamp 05:05:13 INFO - PROCESS | 1671 | 05:05:13 INFO - PROCESS | 1671 | ------- Summary: start ------- 05:05:13 INFO - PROCESS | 1671 | Number of tests: 6 05:05:13 INFO - PROCESS | 1671 | 05:05:13 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5913.04 Median:6127.76 stddev:631.39 (10.3%) stddev-sans-first:600.34 05:05:13 INFO - PROCESS | 1671 | Values: 3345.3 3628.5 3633.6 3642.5 3712.5 5930.8 5944.9 5954.9 5965.9 5996.6 5946.8 5987.2 6000.3 6012.2 6019.1 5286.5 5351.4 5383.5 5438.4 5473.9 5422.7 5460.6 5465.2 5480.2 5481.2 5403.2 5441.0 5448.2 5477.7 5492.8 4675.0 5447.6 5460.2 5463.5 5487.2 5300.7 5318.5 5322.4 5400.3 5410.6 5834.0 5834.3 5861.7 5879.2 5915.5 6289.6 6300.3 6337.7 6340.1 6341.0 5555.5 6201.7 6224.4 6412.2 6421.6 6077.3 6083.8 6115.0 6126.1 6171.3 6502.8 6514.7 6518.9 6525.7 6526.8 6194.8 6221.5 6272.3 6296.3 6318.8 6381.4 6382.2 6394.7 6395.2 6400.9 6233.4 6278.6 6295.1 6299.0 6323.6 6213.8 6250.6 6265.9 6320.7 6336.8 5041.0 5067.4 5077.6 5092.3 5112.3 4611.3 4612.2 4615.2 4623.9 4636.2 5876.2 5974.7 6014.6 6111.1 6115.1 6345.5 6386.9 6402.1 6404.7 6420.8 6031.1 6051.5 6074.2 6076.1 6108.0 5416.7 5540.3 5556.2 5590.6 5633.9 6581.1 6606.2 6631.2 6648.8 6656.3 6216.9 6369.4 6385.4 6390.6 6413.9 6518.7 6616.2 6618.2 6632.3 6657.6 6305.1 6319.7 6339.7 6352.0 6379.0 6242.4 6280.4 6342.1 6350.9 6426.6 6229.7 6254.7 6274.7 6279.5 6293.8 5306.5 5401.4 5606.5 6072.3 6260.2 6084.0 6111.7 6165.5 6177.2 6186.5 6127.8 6157.4 6218.8 6219.8 6245.2 6060.5 6128.3 6128.7 6128.7 6131.0 05:05:13 INFO - PROCESS | 1671 | 05:05:13 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:69889.98 Median:35103.97 stddev:151437.70 (431.4%) stddev-sans-first:151772.95 05:05:13 INFO - PROCESS | 1671 | Values: 516.5 523.9 608.9 611.7 641.1 32957.7 33603.3 33604.3 33616.3 33630.3 39299.2 43141.4 43145.8 43163.1 43189.4 9697.9 9897.5 9910.0 9923.9 9950.6 9164.9 9367.5 9679.2 9807.6 11994.0 11885.2 11973.9 11981.0 11987.4 11994.2 11773.4 11853.7 11874.0 11875.8 11889.8 11693.9 11790.1 11791.1 11799.3 11804.2 41305.4 41668.4 41758.4 43783.0 44054.0 3487.7 3537.7 3558.9 3725.0 3998.8 27803.0 28455.9 28648.6 28706.3 28877.3 2695.6 2716.6 2724.5 2740.2 2742.0 407885.0 413800.9 415267.0 415409.0 416173.0 67236.7 67574.0 67701.0 67715.0 67869.0 71916.9 75977.9 75992.6 76008.0 76012.6 18034.9 18258.7 18322.2 18324.9 18327.0 12433.2 13278.6 14702.9 14805.8 14819.0 17924.2 18106.0 18119.5 18139.1 20805.8 7887.0 7898.9 7919.8 7925.3 7969.8 1004.2 1056.3 1056.6 1077.9 1103.6 31740.2 31920.0 32069.7 32130.8 32270.8 1080.4 1105.0 1154.4 1167.8 1175.5 16299.7 16325.6 16383.9 16556.2 16648.8 649366.0 736437.0 738718.0 739265.0 741428.0 51167.7 63895.7 64519.7 64637.0 64644.0 1529.8 1530.3 1543.4 1547.3 1549.5 29213.9 31317.2 32862.0 33025.5 33386.0 58124.7 58512.7 58614.7 58647.4 58702.4 491715.0 507085.0 507142.0 507403.0 573481.0 26264.5 26863.9 29034.7 29912.0 30194.2 41192.0 41202.8 45424.8 45571.3 45577.9 36316.9 37291.1 37419.7 37432.6 38124.0 38202.6 38725.8 38963.4 39448.6 39605.0 39235.8 39432.6 39489.8 39738.8 40151.6 33092.2 33360.6 34527.7 35041.1 35166.8 25780.7 26045.7 26148.5 26278.9 26458.9 05:05:13 INFO - PROCESS | 1671 | 05:05:13 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:35893.71 Median:21326.41 stddev:102182.19 (479.1%) stddev-sans-first:102434.08 05:05:13 INFO - PROCESS | 1671 | Values: 400.9 418.8 422.7 423.9 432.5 16382.3 20853.7 21269.8 21634.7 21653.7 21181.5 21433.8 22279.8 22570.9 22575.9 4700.9 6104.1 6152.0 6158.6 6169.8 4432.6 5772.7 5975.7 6682.2 6847.5 6767.8 6784.3 6786.3 6792.1 6799.3 6416.6 6669.0 6672.0 6676.1 6684.4 5537.5 5837.5 5857.6 5864.1 5866.2 13481.7 13674.9 13715.8 13741.2 14604.7 2196.8 2308.4 2369.7 2384.2 2510.2 9854.8 10193.2 11236.6 11298.2 14677.8 1807.8 1829.0 1850.0 1857.2 1903.4 107817.0 109592.0 119872.0 120281.0 121280.6 11257.1 11554.9 11719.2 12021.0 12144.5 36036.8 36558.9 36972.3 37006.3 37010.6 11318.4 11437.9 11583.8 11601.4 11914.9 8551.0 9607.5 9745.2 10001.0 10167.1 10105.6 10182.8 10213.6 10258.6 10268.1 3332.9 3968.6 3975.7 4009.4 4125.4 913.6 969.5 978.1 987.1 995.7 20040.3 21324.3 21328.6 21397.5 21706.8 900.9 991.5 1004.6 1018.7 1116.3 11097.7 11325.5 11364.7 11431.0 11446.0 439520.0 639102.0 650452.0 654766.0 663968.0 92289.5 111371.0 127103.0 127690.0 128872.4 465.7 488.9 489.4 489.6 491.0 8313.9 17343.1 18818.1 19392.0 19561.1 40082.8 47501.3 51503.7 51529.7 53217.2 75695.0 77190.6 80113.0 82447.9 83147.2 10252.0 11205.9 12092.0 12142.3 12501.7 13155.8 13341.6 13545.1 13701.0 13862.5 10300.6 11420.7 11481.9 11641.8 11740.2 11344.3 11444.1 11449.5 11828.2 11873.6 6034.9 8276.5 11656.4 11859.1 12075.6 12221.9 12277.6 12486.4 12667.2 12738.0 11144.4 11731.5 11761.6 11819.6 12108.3 05:05:13 INFO - PROCESS | 1671 | 05:05:13 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5917.91 Median:2750.37 stddev:3335.30 (121.3%) stddev-sans-first:3328.04 05:05:13 INFO - PROCESS | 1671 | Values: 1488.5 1617.6 1631.9 1887.3 2006.5 8348.5 9014.1 9168.2 9172.4 9183.9 8815.2 9052.4 9064.7 9075.3 9087.1 2564.4 2591.8 2592.9 2593.9 2594.7 2327.3 2459.0 2503.9 2574.0 2588.3 2548.8 2575.6 2597.6 2600.9 2604.6 2281.7 2455.8 2573.5 2584.0 2588.9 2535.1 2574.2 2581.0 2583.4 2587.5 8928.0 9189.1 9252.3 9266.9 9281.9 5564.4 5641.5 5661.5 5682.9 5692.7 3601.9 3693.0 3728.3 3730.6 3766.2 1028.7 1060.6 1065.7 1065.8 1067.9 12384.0 12691.8 12696.2 12715.2 12725.7 5027.9 5034.6 5133.5 5176.8 5196.5 10930.1 10931.3 10934.0 10938.0 10955.2 5899.7 5906.1 5909.0 5917.3 5930.2 4289.2 4658.5 4732.6 5262.7 5306.6 4222.3 4249.7 4335.0 4354.6 4370.8 1379.0 1418.6 1419.7 1430.8 1435.5 896.1 932.2 933.3 950.0 984.6 6001.7 6053.6 6074.9 6175.3 6220.0 491.6 492.7 493.8 501.5 502.0 3328.5 3940.2 4079.6 4095.0 4116.8 10797.1 11135.2 11262.0 11275.9 11282.2 11028.1 11046.3 11133.3 11139.9 11163.9 7100.5 8648.8 8649.7 8665.1 8666.7 5570.7 5833.8 5851.3 5884.1 5956.9 9895.5 9945.6 10070.9 10091.0 10104.6 9927.1 10067.8 10155.9 10389.5 10454.8 6879.1 7057.8 7432.7 7764.8 7840.5 7728.5 7991.4 8501.2 8511.7 8572.3 7455.0 7599.3 7647.0 7657.8 7721.8 7421.8 7477.6 7482.6 7598.8 7672.8 6789.5 6871.3 6881.1 6922.8 6941.1 4840.2 4842.1 4913.7 5027.7 5039.3 5007.0 5034.9 5065.7 5131.1 5299.6 05:05:13 INFO - PROCESS | 1671 | 05:05:13 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:5856.27 Median:6705.90 stddev:1879.91 (28.0%) stddev-sans-first:1838.95 05:05:13 INFO - PROCESS | 1671 | Values: 336.2 343.5 349.3 353.0 356.6 6661.4 6661.8 6674.5 6691.0 6717.9 6620.7 6638.9 6646.0 6655.9 6693.9 4722.6 4805.3 4858.1 4912.0 4925.9 4850.7 4900.6 4919.0 4930.2 4937.7 4849.2 4869.0 4869.2 4925.1 4925.3 4701.4 4790.4 4909.5 4935.8 4979.8 4231.0 4241.1 4258.4 4258.9 4269.0 6403.8 6433.5 6447.2 6451.8 6459.4 5828.5 5834.1 5836.3 5842.7 5852.5 5728.7 7090.0 7106.0 7116.4 7133.1 4441.6 4580.6 4586.8 4587.6 4599.1 7165.1 7488.3 7491.4 7500.1 7511.8 4691.4 4693.2 4699.0 4707.3 4707.5 4750.1 4755.5 4761.1 4768.4 4772.3 5170.2 5222.0 5693.7 5699.1 5701.1 6838.6 7067.2 7091.6 7186.2 7207.7 1637.9 1647.2 1660.7 1662.4 1668.7 996.5 1043.1 1044.0 1062.0 1070.4 6622.1 6745.5 6777.0 6777.9 6789.7 7445.8 7448.2 7451.7 7454.5 7471.6 6738.1 6740.4 6741.2 6747.7 6777.9 3870.1 3903.5 3913.2 3922.8 3925.7 7489.7 7719.0 7733.7 7748.7 7748.9 7645.9 7729.7 7731.7 7735.8 7737.9 7810.3 7815.3 7816.0 7819.8 7825.8 6937.5 7167.5 7399.8 7444.0 7451.9 7265.4 7333.6 7335.5 7336.4 7340.9 7785.7 7789.3 7792.0 7799.2 7813.6 4247.8 4499.3 4676.9 4750.0 4755.5 7119.0 7367.3 7431.4 7433.3 7437.2 7151.5 7164.1 7175.7 7190.2 7198.8 7135.2 7167.9 7181.4 7187.1 7195.1 6981.7 7002.8 7004.3 7020.1 7022.4 6261.4 6936.0 7066.2 7157.1 7170.9 6719.5 6719.9 6899.7 6955.8 7013.5 05:05:13 INFO - PROCESS | 1671 | 05:05:13 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17845.86 Median:553.30 stddev:24496.83 (4427.4%) stddev-sans-first:24531.99 05:05:13 INFO - PROCESS | 1671 | Values: 770.6 779.2 785.7 791.9 793.6 15108.6 15489.1 15498.8 15526.8 15565.0 13129.3 14247.1 14388.4 14401.6 14429.1 950.0 952.4 966.5 968.0 968.2 801.5 961.6 962.9 963.8 965.6 1020.3 1020.7 1021.0 1024.2 1026.7 1015.0 1015.1 1016.0 1016.3 1016.8 754.9 755.0 755.6 762.8 765.7 13083.4 13133.2 13144.6 13187.2 15288.8 12319.0 12348.3 12393.3 12587.2 13533.3 9974.2 10033.4 10185.2 10398.2 10581.9 3484.6 3526.4 3542.6 3574.4 3664.0 73068.6 78031.0 80207.0 82884.2 83307.6 9609.2 9676.4 9787.0 9952.6 10305.2 21604.6 21703.3 21757.7 21763.7 21807.7 7555.2 7567.5 7708.7 7712.6 7728.3 4125.8 4166.0 4194.5 4202.8 4253.2 3838.9 3880.6 3894.1 3894.7 3905.4 687.4 695.5 697.1 711.7 724.7 540.2 575.3 587.0 603.6 613.5 8352.2 8357.0 8367.5 8377.5 8463.6 566.4 582.2 599.4 617.7 635.5 3814.7 3905.0 3956.2 3962.8 3990.5 89185.0 89705.6 90239.0 91144.0 91331.5 63692.7 64130.7 66799.3 74443.0 75425.6 67160.7 69059.0 69188.7 69696.7 69907.0 8315.7 8557.4 8603.5 8607.0 8850.7 45334.6 46168.6 47706.5 49613.5 50327.0 60763.0 60930.1 62831.4 65840.7 66257.0 11217.6 11334.6 11377.0 11727.2 11773.4 12091.9 12255.1 12265.3 12448.0 12544.1 11570.4 11803.5 11977.8 12020.0 12121.3 11533.4 11752.6 11779.3 12059.2 12344.9 11333.3 11455.4 11540.1 11629.5 12472.9 8905.2 9136.9 9159.1 9369.0 9612.1 9860.0 9916.9 9941.0 9948.3 9950.5 05:05:13 INFO - PROCESS | 1671 | -------- Summary: end -------- 05:05:13 INFO - PROCESS | 1671 | 05:05:14 INFO - TEST-INFO | 1671: exit 0 05:05:14 INFO - TEST-OK | dromaeo_css | took 1112883ms 05:05:14 INFO - TEST-START | kraken 05:05:14 INFO - operating with platform_type : mac_ 05:05:14 INFO - Initialising browser for kraken test... 05:05:14 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpOHkAjv/profile http://localhost:49277/getInfo.html) 05:05:16 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 05:05:16 INFO - PROCESS | 1677 | colorDepth:24 05:05:16 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 05:05:16 INFO - PROCESS | 1677 | __metrics 05:05:16 INFO - PROCESS | 1677 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:05:16 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:05:16 INFO - TEST-INFO | 1677: exit 0 05:05:16 INFO - Browser initialized. 05:05:16 INFO - Running cycle 1/1 for kraken test... 05:05:16 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpOHkAjv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:28 INFO - PROCESS | 1680 | RSS: Main: 160935936 05:05:28 INFO - PROCESS | 1680 | 05:05:31 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 05:05:32 INFO - PROCESS | 1680 | RSS: Main: 223977472 05:05:32 INFO - PROCESS | 1680 | 05:05:34 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 05:05:35 INFO - PROCESS | 1680 | RSS: Main: 239632384 05:05:35 INFO - PROCESS | 1680 | 05:05:38 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 05:05:39 INFO - PROCESS | 1680 | RSS: Main: 324632576 05:05:39 INFO - PROCESS | 1680 | 05:05:41 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 05:05:41 INFO - PROCESS | 1680 | RSS: Main: 231473152 05:05:41 INFO - PROCESS | 1680 | 05:05:44 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:05:44 INFO - PROCESS | 1680 | RSS: Main: 231059456 05:05:44 INFO - PROCESS | 1680 | 05:05:47 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 05:05:47 INFO - PROCESS | 1680 | RSS: Main: 270282752 05:05:47 INFO - PROCESS | 1680 | 05:05:50 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 05:05:51 INFO - PROCESS | 1680 | RSS: Main: 272003072 05:05:51 INFO - PROCESS | 1680 | 05:05:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 05:05:54 INFO - PROCESS | 1680 | RSS: Main: 268742656 05:05:54 INFO - PROCESS | 1680 | 05:05:56 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:05:57 INFO - PROCESS | 1680 | RSS: Main: 261103616 05:05:57 INFO - PROCESS | 1680 | 05:05:58 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:05:59 INFO - PROCESS | 1680 | RSS: Main: 267821056 05:05:59 INFO - PROCESS | 1680 | 05:06:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:06:02 INFO - PROCESS | 1680 | RSS: Main: 271380480 05:06:02 INFO - PROCESS | 1680 | 05:06:04 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:06:05 INFO - PROCESS | 1680 | RSS: Main: 278364160 05:06:05 INFO - PROCESS | 1680 | 05:06:08 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:06:08 INFO - PROCESS | 1680 | RSS: Main: 275582976 05:06:08 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 05:06:10 INFO - PROCESS | 1680 | RSS: Main: 336363520 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | __start_tp_report 05:06:10 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 05:06:10 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 05:06:10 INFO - PROCESS | 1680 | |i|pagename|runs| 05:06:10 INFO - PROCESS | 1680 | |0;ai-astar;113;113;117;114;112;112;114;113;114;134 05:06:10 INFO - PROCESS | 1680 | |1;audio-beat-detection;156;119;145;163;116;140;135;116;163;115 05:06:10 INFO - PROCESS | 1680 | |2;audio-dft;184;185;185;186;184;185;184;197;263;207 05:06:10 INFO - PROCESS | 1680 | |3;audio-fft;70;77;72;158;61;76;82;87;160;56 05:06:10 INFO - PROCESS | 1680 | |4;audio-oscillator;89;88;146;85;113;139;132;78;78;125 05:06:10 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;86;84;86;83;86;83;87;84;87;83 05:06:10 INFO - PROCESS | 1680 | |6;imaging-darkroom;92;108;148;92;92;92;92;117;151;110 05:06:10 INFO - PROCESS | 1680 | |7;imaging-desaturate;86;84;88;86;85;83;86;86;87;85 05:06:10 INFO - PROCESS | 1680 | |8;json-parse-financial;55;60;62;60;62;62;63;76;58;56 05:06:10 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;60;64;64;76;71;67;64;63;66;65 05:06:10 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;80;80;79;80;80;75;81;80;80;83 05:06:10 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;129;146;146;117;122;126;124;125;152;160 05:06:10 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;155;162;162;158;162;161;163;168;160;156 05:06:10 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;64;65;65;67;67;65;66;68;67;66 05:06:10 INFO - PROCESS | 1680 | __end_tp_report 05:06:10 INFO - PROCESS | 1680 | __start_cc_report 05:06:10 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1890 05:06:10 INFO - PROCESS | 1680 | __end_cc_report 05:06:10 INFO - PROCESS | 1680 | __startTimestamp1467029170825__endTimestamp 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | ------- Summary: start ------- 05:06:10 INFO - PROCESS | 1680 | Number of tests: 14 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:115.60 Median:114.00 stddev:6.62 (5.8%) stddev-sans-first:6.95 05:06:10 INFO - PROCESS | 1680 | Values: 113.0 113.0 117.0 114.0 112.0 112.0 114.0 113.0 114.0 134.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:142.50 stddev:19.66 (13.8%) stddev-sans-first:19.59 05:06:10 INFO - PROCESS | 1680 | Values: 156.0 119.0 145.0 163.0 116.0 140.0 135.0 116.0 163.0 115.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:196.00 Median:185.50 stddev:24.73 (13.3%) stddev-sans-first:25.85 05:06:10 INFO - PROCESS | 1680 | Values: 184.0 185.0 185.0 186.0 184.0 185.0 184.0 197.0 263.0 207.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:89.90 Median:74.00 stddev:37.54 (50.7%) stddev-sans-first:39.12 05:06:10 INFO - PROCESS | 1680 | Values: 70.0 77.0 72.0 158.0 61.0 76.0 82.0 87.0 160.0 56.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:107.30 Median:78.00 stddev:26.63 (34.1%) stddev-sans-first:27.40 05:06:10 INFO - PROCESS | 1680 | Values: 89.0 88.0 146.0 85.0 113.0 139.0 132.0 78.0 78.0 125.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.72 05:06:10 INFO - PROCESS | 1680 | Values: 86.0 84.0 86.0 83.0 86.0 83.0 87.0 84.0 87.0 83.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:109.40 Median:92.00 stddev:23.08 (25.1%) stddev-sans-first:23.61 05:06:10 INFO - PROCESS | 1680 | Values: 92.0 108.0 148.0 92.0 92.0 92.0 92.0 117.0 151.0 110.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.43 (1.7%) stddev-sans-first:1.51 05:06:10 INFO - PROCESS | 1680 | Values: 86.0 84.0 88.0 86.0 85.0 83.0 86.0 86.0 87.0 85.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:62.00 stddev:5.80 (9.3%) stddev-sans-first:5.67 05:06:10 INFO - PROCESS | 1680 | Values: 55.0 60.0 62.0 60.0 62.0 62.0 63.0 76.0 58.0 56.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:65.50 stddev:4.52 (6.9%) stddev-sans-first:4.24 05:06:10 INFO - PROCESS | 1680 | Values: 60.0 64.0 64.0 76.0 71.0 67.0 64.0 63.0 66.0 65.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:80.00 stddev:1.99 (2.5%) stddev-sans-first:2.11 05:06:10 INFO - PROCESS | 1680 | Values: 80.0 80.0 79.0 80.0 80.0 75.0 81.0 80.0 80.0 83.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:134.70 Median:137.50 stddev:14.86 (10.8%) stddev-sans-first:15.61 05:06:10 INFO - PROCESS | 1680 | Values: 129.0 146.0 146.0 117.0 122.0 126.0 124.0 125.0 152.0 160.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.70 Median:162.00 stddev:3.74 (2.3%) stddev-sans-first:3.35 05:06:10 INFO - PROCESS | 1680 | Values: 155.0 162.0 162.0 158.0 162.0 161.0 163.0 168.0 160.0 156.0 05:06:10 INFO - PROCESS | 1680 | 05:06:10 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:1.25 (1.9%) stddev-sans-first:1.09 05:06:10 INFO - PROCESS | 1680 | Values: 64.0 65.0 65.0 67.0 67.0 65.0 66.0 68.0 67.0 66.0 05:06:10 INFO - PROCESS | 1680 | -------- Summary: end -------- 05:06:10 INFO - PROCESS | 1680 | 05:06:11 INFO - TEST-INFO | 1680: exit 0 05:06:11 INFO - TEST-OK | kraken | took 57431ms 05:06:11 INFO - SUITE-END | took 1170s 05:06:11 INFO - Completed test suite (00:19:30) 05:06:11 INFO - javascript benchmark 05:06:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.330933813237, 3628.546431696038, 3633.5821380541247, 3642.4900513928055, 3712.4988126602902, 5930.792422265222, 5944.851378715534, 5954.880902381949, 5965.88068238635, 5996.610220335675, 5946.791862284821, 5987.221661184051, 6000.33996260411, 6012.2184116064855, 6019.097135429688, 5286.471352864722, 5351.357837059559, 5383.515483606475, 5438.401775804658, 5473.890522189554, 5422.728863556826, 5460.645058071213, 5465.234867118606, 5480.150576660604, 5481.205225242337, 5403.189521571776, 5440.9934162180125, 5448.210009548613, 5477.726113694319, 5492.752826122814, 4674.994876101655, 5447.645903016291, 5460.180972854104, 5463.535599474009, 5487.176923461473, 5300.681959082467, 5318.494742999409, 5322.361316641989, 5400.270963419927, 5410.621256512046, 5834.008218602848, 5834.2998840139035, 5861.736221870026, 5879.235699359098, 5915.526757859361, 6289.559730818844, 6300.275468321156, 6337.651429171394, 6340.112384266206, 6341.0, 5555.500004999529, 6201.720922558496, 6224.439800417938, 6412.198475190601, 6421.614703117782, 6077.270727512682, 6083.756649734005, 6114.9694251529345, 6126.111713801474, 6171.25944886612, 6502.804915852532, 6514.739410423625, 6518.934810651833, 6525.738970441129, 6526.804195874131, 6194.752209911643, 6221.5333849961435, 6272.278687950853, 6296.275928268216, 6318.8420289492215, 6381.3937675920715, 6382.20222472191, 6394.712237949303, 6395.232572091379, 6400.871982560323, 6233.407826256498, 6278.623282603058, 6295.087212354183, 6298.968505157445, 6323.588966717149, 6213.751449942042, 6250.562460627803, 6265.937340626627, 6320.652364120017, 6336.841578960563, 5040.974795126001, 5067.442581316052, 5077.568406685473, 5092.28707980883, 5112.258722485219, 4611.262198048296, 4612.238980568236, 4615.215413379734, 4623.9075218495445, 4636.188666983265, 5876.236089308362, 5974.731137098841, 6014.609050411709, 6111.113888486234, 6115.082737589397, 6345.524085693592, 6386.936130638727, 6402.071699603532, 6404.679766011681, 6420.839479013062, 6031.125486804476, 6051.485623722033, 6074.210352654127, 6076.118962750376, 6107.969460152671, 5416.7020813855615, 5540.335159780852, 5556.16657501378, 5590.636608620427, 5633.859153521113, 6581.144451221407, 6606.174228221473, 6631.20425548918, 6648.767293144717, 6656.334366563305, 6216.937830621636, 6369.363063693593, 6385.425311721968, 6390.616563006234, 6413.8717225656155, 6518.706658200487, 6616.172978377703, 6618.205815302194, 6632.336766323329, 6657.567258128206, 6305.054241863573, 6319.715612797527, 6339.651319177489, 6352.0, 6379.011253255753, 6242.438180563771, 6280.371962803683, 6342.143810585512, 6350.872982540416, 6426.646534440651, 6229.657368844757, 6254.749810007731, 6274.686265686789, 6279.497640188682, 6293.811185664437, 5306.548943339705, 5401.432849550741, 5606.523445507178, 6072.271327440735, 6260.186175797299, 6083.969580152071, 6111.663858487648, 6165.475934545614, 6177.227846519185, 6186.505079593531, 6127.758165267708, 6157.353477884758, 6218.782342618168, 6219.813405597839, 6245.18812554365, 6060.454559089703, 6128.325884152829, 6128.66292353907, 6128.693565321805, 6130.9577371846], "value": 5874.809597333099, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.5428595692812, 523.8585581892889, 608.8812681527104, 611.6788685939878, 641.0640464921215, 32957.670423295756, 33603.327933441375, 33604.327913441746, 33616.32767344652, 33630.32739345211, 39299.21401571967, 43141.35287970683, 43145.78427107868, 43163.115319621225, 43189.35215971763, 9697.903020969807, 9897.50512474377, 9909.950450247774, 9923.851142232872, 9950.552225149886, 9164.8625270621, 9367.531623418836, 9679.225661947028, 9807.558659860324, 11994.040476761882, 11885.227460215101, 11973.880261197408, 11980.98161656256, 11987.40062996851, 11994.220375675553, 11773.352465614387, 11853.703657408547, 11873.990710789592, 11875.82186267211, 11889.821652675217, 11693.883061169365, 11790.056795456429, 11791.056715462742, 11799.292042477393, 11804.173707840455, 41305.38041929373, 41668.37497437541, 41758.37362439566, 43783.0, 44054.0, 3487.668671476206, 3537.6970579000103, 3558.857645694169, 3724.981375093107, 3998.8000599969887, 27803.0, 28455.857720711472, 28648.570271445944, 28706.282342941384, 28877.278068048257, 2695.649565556465, 2716.5925111233278, 2724.5368287391193, 2740.2190375742816, 2742.012875364867, 407885.0, 413800.9309953461, 415267.0, 415409.0, 416173.0, 67236.66381668061, 67574.0, 67701.0, 67715.0, 67869.0, 71916.92124618134, 75977.86033209506, 75992.62003689946, 76008.0, 76012.61993689997, 18034.917904925747, 18258.726119108225, 18322.175502102436, 18324.90837545804, 18326.992015439013, 12433.191842530206, 13278.601641950758, 14702.852971470362, 14805.777913331309, 14819.0, 17924.193411296525, 18106.0, 18119.547011324823, 18139.09304534768, 20805.791942080687, 7886.960565197137, 7898.921010789819, 7919.802004949807, 7925.286724194735, 7969.800754981172, 1004.2468148888333, 1056.3186744549876, 1056.5562463765236, 1077.9029887310023, 1103.624767579027, 31740.206494837814, 31920.0, 32069.67930320667, 32130.83934580312, 32270.83864580662, 1080.438172150493, 1105.0, 1154.3708678769922, 1167.795635763738, 1175.535663412947, 16299.674006519803, 16325.59186020359, 16383.918080409521, 16556.172191390382, 16648.833511664972, 649366.0, 736437.0, 738718.0, 739265.0, 741428.0, 51167.744161278955, 63895.68052159803, 64519.67740161363, 64637.0, 64644.0, 1529.755239161751, 1530.3266562712372, 1543.398074750848, 1547.257316488092, 1549.527394144774, 29213.85393073021, 31317.23976561631, 32862.0, 33025.50461743124, 33386.0, 58124.709376454535, 58512.707436462544, 58614.70692646509, 58647.4135258659, 58702.412975869694, 491715.0, 507085.0, 507142.0, 507403.0, 573481.0, 26264.4747105053, 26863.86568067147, 29034.709652903202, 29912.0, 30194.24514387158, 41192.0, 41202.79398602988, 45424.77287613541, 45571.316430254235, 45577.860553486425, 36316.91049268526, 37291.06772330713, 37419.69031083899, 37432.625673742914, 38124.04689882775, 38202.617973819906, 38725.80637096796, 38963.415548767356, 39448.605513944494, 39605.00987475336, 39235.822925312284, 39432.6056739429, 39489.802550987064, 39738.801305993285, 40151.59848401479, 33092.17269568184, 33360.583044734194, 34527.65472345244, 35041.12397190091, 35166.82416587901, 25780.742192578586, 26045.739542604333, 26148.477030459664, 26278.94884204687, 26458.86770566135], "value": 20931.679708189033, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.9478767760191, 418.82409388057033, 422.7379025004497, 423.86648205815163, 432.5307041859582, 16382.344706211752, 20853.663413853785, 21269.787302126955, 21634.675479867816, 21653.67519487209, 21181.470463238387, 21433.785662143375, 22279.77720222797, 22570.887145564247, 22575.887120564374, 4700.882477938053, 6104.08438734189, 6151.969240153793, 6158.56890017699, 6169.753209871622, 4432.578905004036, 5772.682502462363, 5975.671338076404, 6682.198136223636, 6847.520673552854, 6767.796966091025, 6784.321567843201, 6786.253512113663, 6792.117024786796, 6799.286074962132, 6416.550841441078, 6669.032990216391, 6671.966640166816, 6676.098726671886, 6684.398404143651, 5537.473939975695, 5837.474627283523, 5857.619254748428, 5864.06175011996, 5866.178734977107, 13481.730365392637, 13674.93162534191, 13715.794263086062, 13741.24423156726, 14604.70790584193, 2196.8242540596716, 2308.3998160478227, 2369.6563998220163, 2384.225126833786, 2510.1590967025886, 9854.753631159278, 10193.23550733698, 11236.606718764886, 11298.209125361229, 14677.77983330251, 1807.7921039080543, 1828.9725654115198, 1850.0009994603033, 1857.1735577667944, 1903.381401044666, 107816.99999999921, 109592.0, 119872.0, 120281.0, 121280.57438851261, 11257.099432045417, 11554.94222528882, 11719.238249513925, 12021.0, 12144.453499592539, 36036.819815901275, 36558.90323290305, 36972.26055478875, 37006.29937006278, 37010.629893701254, 11318.434078296052, 11437.885621143681, 11583.826242606368, 11601.419929003516, 11914.880851191378, 8550.957245213734, 9607.471589062528, 9745.220382369393, 10000.999900009941, 10167.135793457639, 10105.595776169019, 10182.796344073226, 10213.642522511676, 10258.640947566948, 10268.127209187154, 3332.8833490827706, 3968.5634580196156, 3975.6819454443576, 4009.4363198352667, 4125.443065186221, 913.6299798581585, 969.5152423788106, 978.1196922769423, 987.1411871671692, 995.7162208770466, 20040.298589549588, 21324.253651122137, 21328.573428531654, 21397.46506337354, 21706.78293217079, 900.9009009009004, 991.4844280973854, 1004.5780772075746, 1018.6689325969089, 1116.2911551164907, 11097.667069987914, 11325.490352934137, 11364.715882103013, 11431.0, 11446.0, 439520.0, 639102.0224494425, 650452.0, 654766.0, 663968.0, 92289.53855230815, 111371.0, 127103.0, 127690.0, 128872.35563822309, 465.6717014504766, 488.8899997500534, 489.40781654198105, 489.6009752052066, 490.95581397673664, 8313.875291870747, 17343.132843358035, 18818.059097045367, 19392.0, 19561.119749611586, 40082.799586001885, 47501.287480687126, 51503.74248128735, 51529.742351288005, 53217.20174197313, 75695.0, 77190.6140469294, 80113.0, 82447.93880153044, 83147.16852831394, 10252.0, 11205.887941120485, 12092.0, 12142.300077989265, 12501.749965000467, 13155.802662960255, 13341.599752007456, 13545.051846371054, 13701.0, 13862.514811981533, 10300.587976480856, 11420.714482138013, 11481.942590286995, 11641.76716465649, 11740.236884602473, 11344.319340839576, 11444.084473241954, 11449.484773185393, 11828.17202795796, 11873.643790686294, 6034.939650603438, 8276.503409795432, 11656.426382438261, 11859.110566707704, 12075.637730868088, 12221.877781222074, 12277.631671049883, 12486.438110284877, 12667.239965602093, 12738.0, 11144.387058711525, 11731.530738770354, 11761.647150585497, 11819.586314479297, 12108.273503589813], "value": 10401.745091619481, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.4641529049534, 1617.5632579203614, 1631.9020858748472, 1887.311131437025, 2006.5284658105352, 8348.499090054602, 9014.143656352644, 9168.174864262215, 9172.357934944557, 9183.908160918381, 8815.162559556848, 9052.366334356597, 9064.682736104238, 9075.273978081754, 9087.091290870909, 2564.358910272432, 2591.753783390575, 2592.8833202505934, 2593.9351516212055, 2594.675665541807, 2327.278543651465, 2459.004103338142, 2503.912363067293, 2574.034736973635, 2588.3011586871535, 2548.808839337057, 2575.626534152556, 2597.571400718879, 2600.9349766255805, 2604.5572252717084, 2281.6577513372963, 2455.754424557539, 2573.45957348956, 2584.0, 2588.8576128312934, 2535.0620270500035, 2574.1505303249883, 2581.0450133450554, 2583.3799888026924, 2587.45663410684, 8928.0, 9189.081091890825, 9252.306077044248, 9266.934302555157, 9281.860772088425, 5564.415736347666, 5641.464060914206, 5661.46216109469, 5682.943170568283, 5692.658440493584, 3601.927961440783, 3692.9815350923072, 3728.3475391806323, 3730.570984336809, 3766.2090960898245, 1028.7325295423243, 1060.6075751971744, 1065.6802959112235, 1065.8241390170613, 1067.871855377352, 12384.009279257638, 12691.809622855664, 12696.1747486414, 12715.173513721578, 12725.745485090247, 5027.874303142377, 5034.647574669738, 5133.512316329943, 5176.767045482962, 5196.4803519648485, 10930.070943969851, 10931.289466184831, 10934.015938565408, 10938.0, 10955.17836162291, 5899.675517846474, 5906.084556893688, 5908.970455147697, 5917.289925208916, 5930.199423077829, 4289.249381358312, 4658.464276608166, 4732.5977291929885, 5262.657927234794, 5306.602004849575, 4222.303319952235, 4249.702520823513, 4335.046289816235, 4354.586314300136, 4370.847020354272, 1378.9724205515834, 1418.581518452059, 1419.6592817723879, 1430.799688043654, 1435.4904009076627, 896.1038961038961, 932.2355668352022, 933.2906990687127, 950.0, 984.5815528400401, 6001.669908155006, 6053.636781793107, 6074.878502429927, 6175.320714721377, 6220.0, 491.55268705477846, 492.65514140101357, 493.7629937629958, 501.51353187408154, 501.9548240658285, 3328.5340052392685, 3940.231654827281, 4079.63283304504, 4094.9869128254477, 4116.773577453209, 10797.08224800885, 11135.220534562503, 11262.04272636835, 11275.887241127484, 11282.210245282751, 11028.117750579935, 11046.33721976684, 11133.33200007986, 11139.944300278446, 11163.939425754541, 7100.5029647925185, 8648.827023459495, 8649.740507784776, 8665.090165532529, 8666.739997800076, 5570.749316280858, 5833.766649333979, 5851.268591426072, 5884.146798714248, 5956.8808623828145, 9895.455749933823, 9945.602175912882, 10070.949645251727, 10091.0, 10104.646337378157, 9927.1375464684, 10067.798644027225, 10155.949220253851, 10389.532471038972, 10454.790904181722, 6879.071325371211, 7057.823554411181, 7432.665530051053, 7764.767056988299, 7840.490368126054, 7728.497647653109, 7991.4006449515355, 8501.19238672325, 8511.74464766058, 8572.314214862672, 7455.0, 7599.278068583476, 7646.9617651911385, 7657.846843063219, 7721.845563088819, 7421.8144546386775, 7477.551346919202, 7482.551046937201, 7598.772036838904, 7672.769816905502, 6789.524733268624, 6871.3128687132885, 6881.071055407656, 6922.79231623032, 6941.062956500808, 4840.152973229741, 4842.128416884994, 4913.70517768935, 5027.698338099725, 5039.319691841554, 5006.974965125151, 5034.874128146826, 5065.746712664426, 5131.10205714006, 5299.602529810202], "value": 4699.979538028291, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.15624781797686, 343.54651859545396, 349.26305495404694, 352.96999755020806, 356.5917025006369, 6661.367170118831, 6661.833454163642, 6674.532782705212, 6690.966545167267, 6717.932820671792, 6620.668966551665, 6638.900416493744, 6645.966770166143, 6655.935050391939, 6693.866122677544, 4722.622190224791, 4805.303231031498, 4858.149823780842, 4912.0175964807, 4925.891674373255, 4850.660453768238, 4900.607951363901, 4918.950810491885, 4930.211166213407, 4937.65436419449, 4849.224124140138, 4868.975655121737, 4869.245266983623, 4925.138100832358, 4925.310456536088, 4701.365315682373, 4790.449098353699, 4909.509049095098, 4935.753212339387, 4979.751012449363, 4230.978845105755, 4241.1093670329265, 4258.382534532507, 4258.893527661802, 4268.9786551067045, 6403.775867844603, 6433.517486188554, 6447.226332840042, 6451.774187903401, 6459.35406459355, 5828.504577110951, 5834.095715164156, 5836.270466191726, 5842.73707683151, 5852.531797456193, 5728.68492232927, 7089.971954066683, 7106.0, 7116.395106415961, 7133.144022717255, 4441.555844415565, 4580.564846339593, 4586.816527338902, 4587.587117159472, 4599.103174880899, 7165.140183178052, 7488.288612581796, 7491.438142139361, 7500.099988001475, 7511.774646760551, 4691.358024691331, 4693.155232058262, 4699.0, 4707.31743897133, 4707.505711900201, 4750.097481478508, 4755.476897541268, 4761.119192949315, 4768.403949506312, 4772.260299653559, 5170.1986192140275, 5221.973890130525, 5693.658380497101, 5699.116636921284, 5701.144828275709, 6838.589684618939, 7067.22260551339, 7091.6454177290925, 7186.209516953131, 7207.747728829466, 1637.8853480256269, 1647.1928754910248, 1660.65126323471, 1662.3683000459748, 1668.7079761041769, 996.5366104761321, 1043.0716662170673, 1043.9530221140067, 1061.994690026545, 1070.352436775755, 6622.106015687917, 6745.460363170934, 6777.0, 6777.864442711217, 6789.660516974231, 7445.813854653568, 7448.180700122982, 7451.701931922771, 7454.515456495311, 7471.626418679044, 6738.079647300496, 6740.427063699641, 6741.191057073084, 6747.662616869137, 6777.932220677829, 3870.0905287257474, 3903.4535165076345, 3913.2369188008634, 3922.764634121962, 3925.725199236083, 7489.737859174903, 7719.0, 7733.729319473791, 7748.651310691032, 7748.883766743504, 7645.923540764632, 7729.729459468892, 7731.729389471341, 7735.845283094307, 7737.92262077372, 7810.29707326332, 7815.29662330393, 7815.960920195363, 7819.843603127905, 7825.7652270430835, 6937.549059311129, 7167.462440316893, 7399.8150046249275, 7444.032275804111, 7451.8882216765705, 7265.418766498773, 7333.6333183341685, 7335.523190992569, 7336.449766267445, 7340.926590734024, 7785.688572457038, 7789.298963093349, 7792.0, 7799.220077992155, 7813.609319533887, 4247.808848601882, 4499.302608095751, 4676.92984605238, 4749.976250118727, 4755.500672429345, 7119.0, 7367.300106489874, 7431.40548756109, 7433.293837085468, 7437.181909990005, 7151.53515021522, 7164.104486939133, 7175.677094530654, 7190.245024272585, 7198.820029499305, 7135.179454362607, 7167.8566428672175, 7181.35367816899, 7187.137543494814, 7195.13658361, 6981.685824137824, 7002.75490357835, 7004.334588214111, 7020.087388639444, 7022.367986881205, 6261.436470717657, 6936.0, 7066.152061752417, 7157.069580954442, 7170.892436613351, 6719.529632925648, 6719.865602687821, 6899.724011039502, 6955.826104347432, 7013.509054366145], "value": 5226.945565038756, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.6416516319914, 779.244133190805, 785.7328508307174, 791.8930944322515, 793.5714714054411, 15108.622284442894, 15489.14809685467, 15498.845011549882, 15526.767098493503, 15565.0, 13129.277889716062, 14247.145171289703, 14388.424463021493, 14401.567952961428, 14429.062110962781, 949.9667511637094, 952.4237836109158, 966.5457235099492, 967.9516024198762, 968.239931653652, 801.53110430398, 961.6105477281712, 962.8988956159608, 963.8120566489538, 965.584798536629, 1020.34187948773, 1020.6886899495651, 1020.9943205942178, 1024.2011231239644, 1026.7073883943078, 1014.9951547967532, 1015.0864222200005, 1015.9796804063877, 1016.2784423059636, 1016.7712264740449, 754.8678981178272, 754.9547027178332, 755.6335177438938, 762.8474305138939, 765.7128576783706, 13083.41124649393, 13133.212007279593, 13144.60566183016, 13187.208767473887, 15288.847111528854, 12319.0, 12348.320842353667, 12393.256404615662, 12587.24476531411, 13533.255670938095, 9974.152197063258, 10033.397996120184, 10185.185185185168, 10398.168146548258, 10581.941805819433, 3484.61669216386, 3526.3828829954655, 3542.574891013069, 3574.410222313315, 3663.9633603663888, 73068.63465682691, 78031.0, 80207.0, 82884.17115828824, 83307.5834620823, 9609.231261499202, 9676.371035882787, 9787.0, 9952.552135153936, 10305.175585953106, 21604.56790864174, 21703.34889953304, 21757.67363489549, 21763.67354489684, 21807.67288490674, 7555.206703296075, 7567.545947243183, 7708.653110609924, 7712.614369281513, 7728.343090837229, 4125.814338354781, 4165.981963678348, 4194.454720886265, 4202.789860506962, 4253.234417804825, 3838.9424158637644, 3880.5537363203593, 3894.065424298148, 3894.6689531389584, 3905.3946638271113, 687.3916583823352, 695.5478938689891, 697.0554898113085, 711.7259854955879, 724.72460465024, 540.1627477409999, 575.323994306688, 587.0020964360604, 603.6076550242377, 613.515322894917, 8352.206540378655, 8356.973468589582, 8367.456115352483, 8377.45546539473, 8463.576821158917, 566.4279078131117, 582.205289779448, 599.3736545310161, 617.6973283091343, 635.5455849067938, 3814.6948244140403, 3905.0, 3956.208758248304, 3962.782046987386, 3990.461287726178, 89185.0, 89705.55147224222, 90239.0, 91144.0, 91331.54334228286, 63692.726145477754, 64130.717385652955, 66799.33200668028, 74443.0, 75425.6228718853, 67160.6641966787, 69059.0, 69188.6540567304, 69696.6515167431, 69906.9513957291, 8315.667373305, 8557.400981931454, 8603.52680602573, 8606.956965215133, 8850.690225842065, 45334.63996080123, 46168.61494155046, 47706.522934771594, 49613.50386496089, 50326.99346013132, 60763.0, 60930.08604871019, 62831.37168628438, 65840.6707966457, 66257.00614490882, 11217.55129794799, 11334.60328888485, 11377.0, 11727.179097463095, 11773.352465614473, 12091.939540302243, 12255.080868935043, 12265.26408415498, 12448.0, 12544.059195560187, 11570.363630000431, 11803.527858885549, 11977.760444791229, 12020.0383969284, 12121.27272363661, 11533.423328833693, 11752.588659397223, 11779.293242405483, 12059.21615095016, 12344.938275308567, 11333.320000799979, 11455.369954652575, 11540.134489913458, 11629.53481860716, 12472.937635311766, 8905.154010369004, 9136.862947055666, 9159.084091590788, 9369.0, 9612.086851749073, 9860.0, 9916.900830991597, 9941.0, 9948.303618746619, 9950.502474876372], "value": 7032.42218704846, "unit": "score"}], "name": "dromaeo_css", "value": 7775.5643012524915, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 117.0, 114.0, 112.0, 112.0, 114.0, 113.0, 114.0, 134.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 119.0, 145.0, 163.0, 116.0, 140.0, 135.0, 116.0, 163.0, 115.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 185.0, 186.0, 184.0, 185.0, 184.0, 197.0, 263.0, 207.0], "value": 196.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 72.0, 158.0, 61.0, 76.0, 82.0, 87.0, 160.0, 56.0], "value": 89.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 146.0, 85.0, 113.0, 139.0, 132.0, 78.0, 78.0, 125.0], "value": 107.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 83.0, 86.0, 83.0, 87.0, 84.0, 87.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 108.0, 148.0, 92.0, 92.0, 92.0, 92.0, 117.0, 151.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 88.0, 86.0, 85.0, 83.0, 86.0, 86.0, 87.0, 85.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 60.0, 62.0, 60.0, 62.0, 62.0, 63.0, 76.0, 58.0, 56.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 64.0, 76.0, 71.0, 67.0, 64.0, 63.0, 66.0, 65.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 80.0, 80.0, 75.0, 81.0, 80.0, 80.0, 83.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 146.0, 146.0, 117.0, 122.0, 126.0, 124.0, 125.0, 152.0, 160.0], "value": 134.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 162.0, 162.0, 158.0, 162.0, 161.0, 163.0, 168.0, 160.0, 156.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 67.0, 67.0, 65.0, 66.0, 68.0, 67.0, 66.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1494.1, "alertThreshold": 2.0}]} 05:06:12 INFO - Return code: 0 05:06:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:06:12 ERROR - # TBPL SUCCESS # 05:06:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:12 INFO - 2016-06-27 05:06:12.164 screenresolution[1684:10318] starting screenresolution argv=screenresolution get 05:06:12 INFO - 2016-06-27 05:06:12.167 screenresolution[1684:10318] Display 0: 1600x1200x32@60 05:06:12 INFO - 2016-06-27 05:06:12.172 screenresolution[1685:10321] starting screenresolution argv=screenresolution list 05:06:12 INFO - Available Modes on Display 0 05:06:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:12 INFO - Intel Iris: 05:06:12 INFO - Chipset Model: Intel Iris 05:06:12 INFO - Type: GPU 05:06:12 INFO - Bus: Built-In 05:06:12 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:12 INFO - Vendor: Intel (0x8086) 05:06:12 INFO - Device ID: 0x0a2e 05:06:12 INFO - Revision ID: 0x0009 05:06:12 INFO - Displays: 05:06:12 INFO - Display: 05:06:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:12 INFO - Display Serial Number: 1600x1200 60 05:06:12 INFO - Main Display: Yes 05:06:12 INFO - Mirror: Off 05:06:12 INFO - Online: Yes 05:06:12 INFO - Rotation: Supported 05:06:12 INFO - Return code: 0 05:06:12 INFO - Running post-action listener: _resource_record_post_action 05:06:12 INFO - Running post-run listener: _resource_record_post_run 05:06:13 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 32558592; Write bytes: 495490048; Read time: 312; Write time: 1628 05:06:13 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 237568; Write bytes: 196433920; Read time: 4; Write time: 489 05:06:13 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 30776832; Write bytes: 298208256; Read time: 294; Write time: 1138 05:06:13 INFO - Running post-run listener: _upload_blobber_files 05:06:13 INFO - Blob upload gear active. 05:06:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:06:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:06:13 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:06:13 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:06:13 INFO - (blobuploader) - INFO - Open directory for files ... 05:06:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:06:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:06:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:14 INFO - (blobuploader) - INFO - Done attempting. 05:06:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:06:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:06:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:16 INFO - (blobuploader) - INFO - Done attempting. 05:06:16 INFO - (blobuploader) - INFO - Iteration through files over. 05:06:16 INFO - Return code: 0 05:06:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:06:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:06:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/859ebfecabb29cce4135111697831c79b09b36c64a416c1ccc25478d8ab888a5b68fd637ac02eb1c5b951a8520df5960feaa83e70fa87b61f820735a66a9e61d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b7e37be260454c06072bc1a56ff9f8d1c99336c662d46d2dec8a8c3dd6446065626dd2acc2221fab2a7ded6de053e1caff23fb603b811a095848e8d23393382"} 05:06:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:06:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:06:16 INFO - Contents: 05:06:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/859ebfecabb29cce4135111697831c79b09b36c64a416c1ccc25478d8ab888a5b68fd637ac02eb1c5b951a8520df5960feaa83e70fa87b61f820735a66a9e61d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b7e37be260454c06072bc1a56ff9f8d1c99336c662d46d2dec8a8c3dd6446065626dd2acc2221fab2a7ded6de053e1caff23fb603b811a095848e8d23393382"} 05:06:16 INFO - Running post-run listener: copy_logs_to_upload_dir 05:06:16 INFO - Copying logs to upload dir... 05:06:16 INFO - mkdir: /builds/slave/test/build/upload/logs 05:06:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.443529 ========= master_lag: 0.96 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2016-06-27 05:06:17.206717) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 05:06:17.210454) ========= 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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-Beta/sha512/859ebfecabb29cce4135111697831c79b09b36c64a416c1ccc25478d8ab888a5b68fd637ac02eb1c5b951a8520df5960feaa83e70fa87b61f820735a66a9e61d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b7e37be260454c06072bc1a56ff9f8d1c99336c662d46d2dec8a8c3dd6446065626dd2acc2221fab2a7ded6de053e1caff23fb603b811a095848e8d23393382"} build_url:https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010838 build_url: 'https://queue.taskcluster.net/v1/task/Ml2LMrcDQ9iVEnpQTiMd3g/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/859ebfecabb29cce4135111697831c79b09b36c64a416c1ccc25478d8ab888a5b68fd637ac02eb1c5b951a8520df5960feaa83e70fa87b61f820735a66a9e61d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b7e37be260454c06072bc1a56ff9f8d1c99336c662d46d2dec8a8c3dd6446065626dd2acc2221fab2a7ded6de053e1caff23fb603b811a095848e8d23393382"}' ========= master_lag: 0.62 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 05:06:17.837894) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:06:17.838227) ========= 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.ILS8nAVdl1/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.Xa2o4KSJuj/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.004953 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 05:06:17.863743) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 05:06:17.864080) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 05:06:17.864442) ========= ========= Total master_lag: 1.66 =========