builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0167 starttime: 1463829971.35 results: success (0) buildid: 20160521031436 builduid: b14ff78280814770b970e1cef8a91945 revision: ee9ba5e8d935736380d5c657c67cf2791741b501 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:11.346654) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:11.347075) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:11.347361) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:11.369026) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:11.369305) ========= 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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-21 04:26:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.1M=0.001s 2016-05-21 04:26:11 (10.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.544796 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:11.933373) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:11.933645) ========= 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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.027374 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:12.003180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-21 04:26:12.003484) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ee9ba5e8d935736380d5c657c67cf2791741b501 --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 ee9ba5e8d935736380d5c657c67cf2791741b501 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-21 04:26:12,118 truncating revision to first 12 chars 2016-05-21 04:26:12,118 Setting DEBUG logging. 2016-05-21 04:26:12,118 attempt 1/10 2016-05-21 04:26:12,118 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ee9ba5e8d935?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-21 04:26:13,043 unpacking tar archive at: mozilla-beta-ee9ba5e8d935/testing/mozharness/ program finished with exit code 0 elapsedTime=1.189399 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-21 04:26:13.247501) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:13.247768) ========= script_repo_revision: ee9ba5e8d935736380d5c657c67cf2791741b501 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:13.248110) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:13.248381) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-21 04:26:13.296648) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-05-21 04:26:13.296912) ========= /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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:26:13 INFO - MultiFileLogger online at 20160521 04:26:13 in /builds/slave/test 04:26:13 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:26:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:26:13 INFO - {'append_to_log': False, 04:26:13 INFO - 'base_work_dir': '/builds/slave/test', 04:26:13 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:26:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:26:13 INFO - 'branch': 'Mozilla-Beta', 04:26:13 INFO - 'buildbot_json_path': 'buildprops.json', 04:26:13 INFO - 'config_files': ('talos/mac_config.py',), 04:26:13 INFO - 'default_actions': ('clobber', 04:26:13 INFO - 'read-buildbot-config', 04:26:13 INFO - 'download-and-extract', 04:26:13 INFO - 'populate-webroot', 04:26:13 INFO - 'create-virtualenv', 04:26:13 INFO - 'install', 04:26:13 INFO - 'run-tests'), 04:26:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:26:13 INFO - 'download_minidump_stackwalk': True, 04:26:13 INFO - 'download_symbols': 'ondemand', 04:26:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:26:13 INFO - 'tooltool.py': '/tools/tooltool.py', 04:26:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:26:13 INFO - '/tools/misc-python/virtualenv.py')}, 04:26:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:26:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:26:13 INFO - 'installer_path': 'installer.exe', 04:26:13 INFO - 'log_level': 'info', 04:26:13 INFO - 'log_name': 'talos', 04:26:13 INFO - 'log_to_console': True, 04:26:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:26:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:26:13 INFO - 'opt_config_files': (), 04:26:13 INFO - 'pip_index': False, 04:26:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:26:13 INFO - 'cmd': ('bash', 04:26:13 INFO - '-c', 04:26:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:26:13 INFO - 'enabled': True, 04:26:13 INFO - 'halt_on_failure': False, 04:26:13 INFO - 'name': 'check_screen_resolution'},), 04:26:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:26:13 INFO - 'cmd': ('bash', 04:26:13 INFO - '-c', 04:26:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:26:13 INFO - 'enabled': True, 04:26:13 INFO - 'halt_on_failure': False, 04:26:13 INFO - 'name': 'check_screen_resolution'},), 04:26:13 INFO - 'run_cmd_checks_enabled': True, 04:26:13 INFO - 'sps_profile': False, 04:26:13 INFO - 'sps_profile_interval': 0, 04:26:13 INFO - 'suite': 'dromaeojs', 04:26:13 INFO - 'system_bits': '32', 04:26:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:26:13 INFO - 'title': 't-yosemite-r7-0167', 04:26:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:26:13 INFO - 'use_talos_json': True, 04:26:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:26:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:26:13 INFO - 'work_dir': 'build'} 04:26:13 INFO - ##### 04:26:13 INFO - ##### Running clobber step. 04:26:13 INFO - ##### 04:26:13 INFO - Running pre-action listener: _resource_record_pre_action 04:26:13 INFO - Running main action method: clobber 04:26:13 INFO - rmtree: /builds/slave/test/build 04:26:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:26:15 INFO - Running post-action listener: _resource_record_post_action 04:26:15 INFO - ##### 04:26:15 INFO - ##### Running read-buildbot-config step. 04:26:15 INFO - ##### 04:26:15 INFO - Running pre-action listener: _resource_record_pre_action 04:26:15 INFO - Running main action method: read_buildbot_config 04:26:15 INFO - Using buildbot properties: 04:26:15 INFO - { 04:26:15 INFO - "project": "", 04:26:15 INFO - "product": "firefox", 04:26:15 INFO - "script_repo_revision": "production", 04:26:15 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 04:26:15 INFO - "repository": "", 04:26:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 04:26:15 INFO - "buildid": "20160521031436", 04:26:15 INFO - "pgo_build": "False", 04:26:15 INFO - "basedir": "/builds/slave/test", 04:26:15 INFO - "buildnumber": 2, 04:26:15 INFO - "slavename": "t-yosemite-r7-0167", 04:26:15 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:26:15 INFO - "platform": "yosemite_r7", 04:26:15 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 04:26:15 INFO - "branch": "mozilla-beta", 04:26:15 INFO - "repo_path": "releases/mozilla-beta", 04:26:15 INFO - "revision": "ee9ba5e8d935736380d5c657c67cf2791741b501", 04:26:15 INFO - "stage_platform": "macosx64", 04:26:15 INFO - "builduid": "b14ff78280814770b970e1cef8a91945", 04:26:15 INFO - "slavebuilddir": "test" 04:26:15 INFO - } 04:26:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 04:26:15 INFO - Running post-action listener: _resource_record_post_action 04:26:15 INFO - ##### 04:26:15 INFO - ##### Running download-and-extract step. 04:26:15 INFO - ##### 04:26:15 INFO - Running pre-action listener: _resource_record_pre_action 04:26:15 INFO - Running main action method: download_and_extract 04:26:15 INFO - mkdir: /builds/slave/test/build/tests 04:26:15 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:26:15 INFO - https://queue.taskcluster.net/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:26:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 04:26:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 04:26:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 04:26:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 04:26:43 INFO - Downloaded 1241 bytes. 04:26:43 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 04:26:43 INFO - Using the following test package requirements: 04:26:43 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 04:26:43 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 04:26:43 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 04:26:43 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 04:26:43 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 04:26:43 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:26:43 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 04:26:43 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 04:26:43 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 04:26:43 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 04:26:43 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 04:26:43 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 04:26:43 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 04:26:43 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 04:26:43 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 04:26:43 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 04:26:43 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 04:26:43 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 04:26:43 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 04:26:43 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:26:43 INFO - https://queue.taskcluster.net/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:26:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 04:26:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 04:26:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 04:26:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 04:26:47 INFO - Downloaded 17747726 bytes. 04:26:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:26:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:26:51 INFO - Return code: 0 04:26:51 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 04:26: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:26:51 INFO - https://queue.taskcluster.net/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:26:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 04:26:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 04:26:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 04:26:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 04:26:56 INFO - Downloaded 17747726 bytes. 04:26:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:26:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:27:00 INFO - Return code: 0 04:27:00 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:27:00 INFO - https://queue.taskcluster.net/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:27:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 04:27:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 04:27:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 04:27:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 04:27:12 INFO - Downloaded 10845194 bytes. 04:27:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:27:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:27:13 INFO - Return code: 0 04:27: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:27:13 INFO - https://queue.taskcluster.net/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 04:27:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.dmg 04:27:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.dmg 04:27:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 04:27:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 04:27:15 INFO - Downloaded 88422596 bytes. 04:27:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.dmg 04:27:15 INFO - mkdir: /builds/slave/test/properties 04:27:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:27:15 INFO - Writing to file /builds/slave/test/properties/build_url 04:27:15 INFO - Contents: 04:27:15 INFO - build_url:https://queue.taskcluster.net/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.dmg 04:27:16 INFO - Running post-action listener: _resource_record_post_action 04:27:16 INFO - Running post-action listener: set_extra_try_arguments 04:27:16 INFO - ##### 04:27:16 INFO - ##### Running populate-webroot step. 04:27:16 INFO - ##### 04:27:16 INFO - Running pre-action listener: _resource_record_pre_action 04:27:16 INFO - Running main action method: populate_webroot 04:27:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:27:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:27:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:27:16 INFO - '--tppagecycles', 04:27:16 INFO - '7'], 04:27:16 INFO - 'tests': ['tsvgm']}}, 04:27:16 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:27:16 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:27:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:27:16 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 04:27:16 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:27:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:27:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:27:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:27:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:27:16 INFO - 'talos_options': ['--e10s'], 04:27:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:27:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:27:16 INFO - 'tests': ['damp', 'tps']}, 04:27:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:27:16 INFO - 'talos_options': ['--e10s'], 04:27:16 INFO - 'tests': ['damp', 'tps']}, 04:27:16 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:27:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:27:16 INFO - 'other': {'tests': ['a11yr', 04:27:16 INFO - 'ts_paint', 04:27:16 INFO - 'tpaint', 04:27:16 INFO - 'sessionrestore', 04:27:16 INFO - 'sessionrestore_no_auto_restore', 04:27:16 INFO - 'tabpaint']}, 04:27:16 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:27:16 INFO - 'tests': ['a11yr', 04:27:16 INFO - 'ts_paint', 04:27:16 INFO - 'tpaint', 04:27:16 INFO - 'sessionrestore', 04:27:16 INFO - 'sessionrestore_no_auto_restore', 04:27:16 INFO - 'tabpaint']}, 04:27:16 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:27:16 INFO - 'tests': ['a11yr', 04:27:16 INFO - 'ts_paint', 04:27:16 INFO - 'tpaint', 04:27:16 INFO - 'sessionrestore', 04:27:16 INFO - 'sessionrestore_no_auto_restore', 04:27:16 INFO - 'tabpaint']}, 04:27:16 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:27:16 INFO - 'tests': ['a11yr', 04:27:16 INFO - 'ts_paint', 04:27:16 INFO - 'tpaint', 04:27:16 INFO - 'sessionrestore', 04:27:16 INFO - 'sessionrestore_no_auto_restore', 04:27:16 INFO - 'tabpaint']}, 04:27:16 INFO - 'other_l64': {'tests': ['a11yr', 04:27:16 INFO - 'ts_paint', 04:27:16 INFO - 'tpaint', 04:27:16 INFO - 'sessionrestore', 04:27:16 INFO - 'sessionrestore_no_auto_restore', 04:27:16 INFO - 'tabpaint']}, 04:27:16 INFO - 'other_nol64': {'tests': ['a11yr', 04:27:16 INFO - 'ts_paint', 04:27:16 INFO - 'tpaint', 04:27:16 INFO - 'sessionrestore', 04:27:16 INFO - 'sessionrestore_no_auto_restore', 04:27:16 INFO - 'tabpaint']}, 04:27:16 INFO - 'svgr': {'tests': ['tsvgx', 04:27:16 INFO - 'tsvgr_opacity', 04:27:16 INFO - 'tart', 04:27:16 INFO - 'tscrollx', 04:27:16 INFO - 'cart']}, 04:27:16 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:27:16 INFO - 'tests': ['tsvgx', 04:27:16 INFO - 'tsvgr_opacity', 04:27:16 INFO - 'tart', 04:27:16 INFO - 'tscrollx', 04:27:16 INFO - 'cart']}, 04:27:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:27:16 INFO - 'tests': ['tp5o']}, 04:27:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:27:16 INFO - 'talos_options': ['--e10s'], 04:27:16 INFO - 'tests': ['tp5o']}, 04:27:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:27:16 INFO - 'talos_options': ['--xperf_path', 04:27:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:27:16 INFO - 'tests': ['tp5n']}, 04:27:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:27:16 INFO - 'talos_options': ['--e10s', 04:27:16 INFO - '--xperf_path', 04:27:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:27:16 INFO - 'tests': ['tp5n']}}, 04:27:16 INFO - 'talos.zip': {'path': '', 04:27:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:27:16 INFO - Running post-action listener: _resource_record_post_action 04:27:16 INFO - ##### 04:27:16 INFO - ##### Running create-virtualenv step. 04:27:16 INFO - ##### 04:27:16 INFO - Running pre-action listener: _resource_record_pre_action 04:27:16 INFO - Running main action method: create_virtualenv 04:27:16 INFO - Creating virtualenv /builds/slave/test/build/venv 04:27:16 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:27:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:27:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:27:16 INFO - Using real prefix '/tools/python27' 04:27:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:27:17 INFO - Installing distribute.............................................................................................................................................................................................done. 04:27:20 INFO - Installing pip.................done. 04:27:20 INFO - Return code: 0 04:27:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:20 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 0x10a83aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6614778d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6ded30>, '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 0x10a542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6d5b20>, '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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:27:20 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:27:20 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:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y86ti3m1Zw/Render', 04:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:20 INFO - 'HOME': '/Users/cltbld', 04:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:20 INFO - 'LOGNAME': 'cltbld', 04:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:20 INFO - 'MOZ_NO_REMOTE': '1', 04:27:20 INFO - 'NO_EM_RESTART': '1', 04:27:20 INFO - 'PAGER': '/bin/cat', 04:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:20 INFO - 'PWD': '/builds/slave/test', 04:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:20 INFO - 'SHELL': '/bin/bash', 04:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TCdJXPdEqc/Listeners', 04:27:20 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:20 INFO - 'USER': 'cltbld', 04:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:20 INFO - 'XPC_FLAGS': '0x0', 04:27:20 INFO - 'XPC_SERVICE_NAME': '0', 04:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:20 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:27:20 INFO - Cleaning up... 04:27:20 INFO - Return code: 0 04:27:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:20 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 0x10a83aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6614778d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6ded30>, '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 0x10a542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6d5b20>, '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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:27:20 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:27:20 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:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y86ti3m1Zw/Render', 04:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:20 INFO - 'HOME': '/Users/cltbld', 04:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:20 INFO - 'LOGNAME': 'cltbld', 04:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:20 INFO - 'MOZ_NO_REMOTE': '1', 04:27:20 INFO - 'NO_EM_RESTART': '1', 04:27:20 INFO - 'PAGER': '/bin/cat', 04:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:20 INFO - 'PWD': '/builds/slave/test', 04:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:20 INFO - 'SHELL': '/bin/bash', 04:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TCdJXPdEqc/Listeners', 04:27:20 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:20 INFO - 'USER': 'cltbld', 04:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:20 INFO - 'XPC_FLAGS': '0x0', 04:27:20 INFO - 'XPC_SERVICE_NAME': '0', 04:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:21 INFO - Downloading/unpacking psutil>=0.7.1 04:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:27:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:27:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:27:24 INFO - Installing collected packages: psutil 04:27:24 INFO - Running setup.py install for psutil 04:27:24 INFO - building 'psutil._psutil_osx' extension 04:27:24 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:27:25 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:27:25 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:27:25 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:27:25 INFO - building 'psutil._psutil_posix' extension 04:27:25 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:27:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:27:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:27:25 INFO - ^ 04:27:25 INFO - 1 warning generated. 04:27:25 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:27:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:27:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:27:25 INFO - Successfully installed psutil 04:27:25 INFO - Cleaning up... 04:27:25 INFO - Return code: 0 04:27:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:25 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 0x10a83aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6614778d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6ded30>, '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 0x10a542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6d5b20>, '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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:27:25 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:27:25 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:27:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y86ti3m1Zw/Render', 04:27:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:25 INFO - 'HOME': '/Users/cltbld', 04:27:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:25 INFO - 'LOGNAME': 'cltbld', 04:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:25 INFO - 'MOZ_NO_REMOTE': '1', 04:27:25 INFO - 'NO_EM_RESTART': '1', 04:27:25 INFO - 'PAGER': '/bin/cat', 04:27:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:25 INFO - 'PWD': '/builds/slave/test', 04:27:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:25 INFO - 'SHELL': '/bin/bash', 04:27:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TCdJXPdEqc/Listeners', 04:27:25 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:27:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:25 INFO - 'USER': 'cltbld', 04:27:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:25 INFO - 'XPC_FLAGS': '0x0', 04:27:25 INFO - 'XPC_SERVICE_NAME': '0', 04:27:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:27:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:27:28 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:27:28 INFO - Installing collected packages: mozsystemmonitor 04:27:28 INFO - Running setup.py install for mozsystemmonitor 04:27:28 INFO - Successfully installed mozsystemmonitor 04:27:28 INFO - Cleaning up... 04:27:28 INFO - Return code: 0 04:27:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a83aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6614778d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6ded30>, '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 0x10a542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6d5b20>, '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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:27:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:27:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y86ti3m1Zw/Render', 04:27:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:28 INFO - 'HOME': '/Users/cltbld', 04:27:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:28 INFO - 'LOGNAME': 'cltbld', 04:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:28 INFO - 'MOZ_NO_REMOTE': '1', 04:27:28 INFO - 'NO_EM_RESTART': '1', 04:27:28 INFO - 'PAGER': '/bin/cat', 04:27:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:28 INFO - 'PWD': '/builds/slave/test', 04:27:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:28 INFO - 'SHELL': '/bin/bash', 04:27:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TCdJXPdEqc/Listeners', 04:27:28 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:27:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:28 INFO - 'USER': 'cltbld', 04:27:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:28 INFO - 'XPC_FLAGS': '0x0', 04:27:28 INFO - 'XPC_SERVICE_NAME': '0', 04:27:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:29 INFO - Downloading/unpacking blobuploader==1.2.4 04:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:31 INFO - Downloading blobuploader-1.2.4.tar.gz 04:27:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:27:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:27:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:32 INFO - Downloading docopt-0.6.1.tar.gz 04:27:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:27:32 INFO - Installing collected packages: blobuploader, requests, docopt 04:27:32 INFO - Running setup.py install for blobuploader 04:27:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:27:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:27:32 INFO - Running setup.py install for requests 04:27:33 INFO - Running setup.py install for docopt 04:27:33 INFO - Successfully installed blobuploader requests docopt 04:27:33 INFO - Cleaning up... 04:27:33 INFO - Return code: 0 04:27:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:33 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 0x10a83aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6614778d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6ded30>, '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 0x10a542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6d5b20>, '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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:27:33 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:27:33 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:27:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y86ti3m1Zw/Render', 04:27:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:33 INFO - 'HOME': '/Users/cltbld', 04:27:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:33 INFO - 'LOGNAME': 'cltbld', 04:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:33 INFO - 'MOZ_NO_REMOTE': '1', 04:27:33 INFO - 'NO_EM_RESTART': '1', 04:27:33 INFO - 'PAGER': '/bin/cat', 04:27:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:33 INFO - 'PWD': '/builds/slave/test', 04:27:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:33 INFO - 'SHELL': '/bin/bash', 04:27:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TCdJXPdEqc/Listeners', 04:27:33 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:27:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:33 INFO - 'USER': 'cltbld', 04:27:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:33 INFO - 'XPC_FLAGS': '0x0', 04:27:33 INFO - 'XPC_SERVICE_NAME': '0', 04:27:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:27:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-HTlGxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:27:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:27:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-E_julY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:27:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:27:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-IQRKJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:27:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:27:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-W6O7Ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:27:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:27:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-_zqGIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:27:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:27:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-0_C793-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:27:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:27:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-YmG_ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:27:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:27:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-F6EX8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:27:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:27:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-V9OFVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:27:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:27:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-s86AKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:27:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:27:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-QswhwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:27:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:27:34 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-bqSJzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:27:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-8WF8Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:27:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-ewoiNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-xhqUVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:27:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-gd1hxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:27:35 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-4oC3mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:27:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:27:35 INFO - Running setup.py install for manifestparser 04:27:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:27:35 INFO - Running setup.py install for mozcrash 04:27:35 INFO - Running setup.py install for mozdebug 04:27:36 INFO - Running setup.py install for mozdevice 04:27:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:27:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:27:36 INFO - Running setup.py install for mozfile 04:27:36 INFO - Running setup.py install for mozhttpd 04:27:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:27:36 INFO - Running setup.py install for mozinfo 04:27:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:27:36 INFO - Running setup.py install for mozInstall 04:27:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:27:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:27:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:27:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:27:36 INFO - Running setup.py install for mozleak 04:27:36 INFO - Running setup.py install for mozlog 04:27:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:27:37 INFO - Running setup.py install for moznetwork 04:27:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:27:37 INFO - Running setup.py install for mozprocess 04:27:37 INFO - Running setup.py install for mozprofile 04:27:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:27:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:27:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:27:37 INFO - Running setup.py install for mozrunner 04:27:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:27:37 INFO - Running setup.py install for mozscreenshot 04:27:37 INFO - Running setup.py install for moztest 04:27:38 INFO - Running setup.py install for mozversion 04:27:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:27:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:27:38 INFO - Cleaning up... 04:27:38 INFO - Return code: 0 04:27:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:27: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:27:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27: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:27:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:38 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 0x10a83aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6614778d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6ded30>, '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 0x10a542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6d5b20>, '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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:27:38 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:27:38 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:27:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y86ti3m1Zw/Render', 04:27:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:38 INFO - 'HOME': '/Users/cltbld', 04:27:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:38 INFO - 'LOGNAME': 'cltbld', 04:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:38 INFO - 'MOZ_NO_REMOTE': '1', 04:27:38 INFO - 'NO_EM_RESTART': '1', 04:27:38 INFO - 'PAGER': '/bin/cat', 04:27:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:38 INFO - 'PWD': '/builds/slave/test', 04:27:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:38 INFO - 'SHELL': '/bin/bash', 04:27:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TCdJXPdEqc/Listeners', 04:27:38 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:27:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:38 INFO - 'USER': 'cltbld', 04:27:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:38 INFO - 'XPC_FLAGS': '0x0', 04:27:38 INFO - 'XPC_SERVICE_NAME': '0', 04:27:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:27:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-qV4aj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:27:38 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:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:27:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-90o4cT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:27:38 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:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:27:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-HZDqS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:27:38 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:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:27:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-_ysEY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:27:38 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:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:27:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-hwIoKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:27:38 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:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:27:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-V0n9zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:27:39 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:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:27:39 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-XdKJgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:27:39 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:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:27:39 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-JMpktE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:27:39 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:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:27:39 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-LrWwX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:27:39 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:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:27:39 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-4UxbyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:27:39 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:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:27:39 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-dEdqd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:27:39 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:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:27:39 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-fyO6G3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:27:39 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:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:27:39 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-2Y796b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:27:39 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:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:27:39 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-rfVg5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:27:39 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:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:39 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-t3F1fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:40 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:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:27:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-sTuNgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:27:40 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:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:27:40 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-MgDmTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:27:40 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:27:40 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:27:40 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:27:40 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:27:40 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:27:40 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:27:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:42 INFO - Downloading blessings-1.6.tar.gz 04:27:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:27:42 INFO - Installing collected packages: blessings 04:27:42 INFO - Running setup.py install for blessings 04:27:42 INFO - Successfully installed blessings 04:27:42 INFO - Cleaning up... 04:27:43 INFO - Return code: 0 04:27:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:27:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:27:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:27:43 INFO - Reading from file tmpfile_stdout 04:27:43 INFO - Current package versions: 04:27:43 INFO - blessings == 1.6 04:27:43 INFO - blobuploader == 1.2.4 04:27:43 INFO - docopt == 0.6.1 04:27:43 INFO - manifestparser == 1.1 04:27:43 INFO - mozInstall == 1.12 04:27:43 INFO - mozcrash == 0.17 04:27:43 INFO - mozdebug == 0.1 04:27:43 INFO - mozdevice == 0.48 04:27:43 INFO - mozfile == 1.2 04:27:43 INFO - mozhttpd == 0.7 04:27:43 INFO - mozinfo == 0.9 04:27:43 INFO - mozleak == 0.1 04:27:43 INFO - mozlog == 3.1 04:27:43 INFO - moznetwork == 0.27 04:27:43 INFO - mozprocess == 0.22 04:27:43 INFO - mozprofile == 0.28 04:27:43 INFO - mozrunner == 6.11 04:27:43 INFO - mozscreenshot == 0.1 04:27:43 INFO - mozsystemmonitor == 0.0 04:27:43 INFO - moztest == 0.7 04:27:43 INFO - mozversion == 1.4 04:27:43 INFO - psutil == 3.1.1 04:27:43 INFO - requests == 1.2.3 04:27:43 INFO - wsgiref == 0.1.2 04:27:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:27:43 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:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:43 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:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:43 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 0x10a83aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6614778d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6ded30>, '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 0x10a542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6d5b20>, '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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:27:43 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:27:43 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:27:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y86ti3m1Zw/Render', 04:27:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:43 INFO - 'HOME': '/Users/cltbld', 04:27:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:43 INFO - 'LOGNAME': 'cltbld', 04:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:43 INFO - 'MOZ_NO_REMOTE': '1', 04:27:43 INFO - 'NO_EM_RESTART': '1', 04:27:43 INFO - 'PAGER': '/bin/cat', 04:27:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:43 INFO - 'PWD': '/builds/slave/test', 04:27:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:43 INFO - 'SHELL': '/bin/bash', 04:27:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TCdJXPdEqc/Listeners', 04:27:43 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:27:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:43 INFO - 'USER': 'cltbld', 04:27:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:43 INFO - 'XPC_FLAGS': '0x0', 04:27:43 INFO - 'XPC_SERVICE_NAME': '0', 04:27:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:43 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:27:43 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:27:43 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:27:43 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:27:43 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:27:43 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:27:43 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:27:43 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:27:43 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:27:43 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:27:43 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:27:43 INFO - Cleaning up... 04:27:43 INFO - Return code: 0 04:27:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:27:43 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:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:43 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:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:43 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 0x10a83aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6614778d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6ded30>, '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 0x10a542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6d5b20>, '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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:27:43 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:27:43 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:27:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y86ti3m1Zw/Render', 04:27:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:43 INFO - 'HOME': '/Users/cltbld', 04:27:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:43 INFO - 'LOGNAME': 'cltbld', 04:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:43 INFO - 'MOZ_NO_REMOTE': '1', 04:27:43 INFO - 'NO_EM_RESTART': '1', 04:27:43 INFO - 'PAGER': '/bin/cat', 04:27:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:43 INFO - 'PWD': '/builds/slave/test', 04:27:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:43 INFO - 'SHELL': '/bin/bash', 04:27:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TCdJXPdEqc/Listeners', 04:27:43 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:27:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:43 INFO - 'USER': 'cltbld', 04:27:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:43 INFO - 'XPC_FLAGS': '0x0', 04:27:43 INFO - 'XPC_SERVICE_NAME': '0', 04:27:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:43 INFO - Downloading/unpacking jsonschema 04:27:43 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:27:43 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:27:43 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:27:43 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:27:43 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:27:43 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:27:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:27:46 INFO - Downloading/unpacking functools32 (from jsonschema) 04:27:46 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:27:46 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:27:46 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:27:46 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:27:46 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:27:46 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:27:46 INFO - Downloading functools32-3.2.3-2.tar.gz 04:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:27:46 INFO - warning: no files found matching '*.txt' 04:27:46 INFO - no previously-included directories found matching 'build' 04:27:46 INFO - no previously-included directories found matching 'dist' 04:27:46 INFO - no previously-included directories found matching '.git*' 04:27:46 INFO - Installing collected packages: jsonschema, functools32 04:27:46 INFO - Running setup.py install for functools32 04:27:46 INFO - warning: no files found matching '*.txt' 04:27:46 INFO - no previously-included directories found matching 'build' 04:27:46 INFO - no previously-included directories found matching 'dist' 04:27:46 INFO - no previously-included directories found matching '.git*' 04:27:46 INFO - Successfully installed jsonschema functools32 04:27:46 INFO - Cleaning up... 04:27:47 INFO - Return code: 0 04:27:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:27:47 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:27:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:47 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:27:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:47 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 0x10a83aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8414c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6614778d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6ded30>, '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 0x10a542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6d5b20>, '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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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:27:47 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:27:47 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:27:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y86ti3m1Zw/Render', 04:27:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:47 INFO - 'HOME': '/Users/cltbld', 04:27:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:47 INFO - 'LOGNAME': 'cltbld', 04:27:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:47 INFO - 'MOZ_NO_REMOTE': '1', 04:27:47 INFO - 'NO_EM_RESTART': '1', 04:27:47 INFO - 'PAGER': '/bin/cat', 04:27:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:47 INFO - 'PWD': '/builds/slave/test', 04:27:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:47 INFO - 'SHELL': '/bin/bash', 04:27:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TCdJXPdEqc/Listeners', 04:27:47 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:27:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:47 INFO - 'USER': 'cltbld', 04:27:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:47 INFO - 'XPC_FLAGS': '0x0', 04:27:47 INFO - 'XPC_SERVICE_NAME': '0', 04:27:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:47 INFO - Downloading/unpacking flake8 04:27: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:27: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:27: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:27: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:27: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:27: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:27:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:27:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:27:49 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:27:49 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:27:49 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:27:49 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:27:49 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:27:49 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:27:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:27:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:27:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:27:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:27:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:27:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:27:51 INFO - no previously-included directories found matching 'docs/_build' 04:27:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:27:51 INFO - Running setup.py install for pep8 04:27:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:27:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:27:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:27:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:27:51 INFO - no previously-included directories found matching 'docs/_build' 04:27:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:27:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:27:51 INFO - Cleaning up... 04:27:51 INFO - Return code: 0 04:27:51 INFO - Running post-action listener: _resource_record_post_action 04:27:51 INFO - Running post-action listener: _start_resource_monitoring 04:27:51 INFO - Starting resource monitoring. 04:27:51 INFO - ##### 04:27:51 INFO - ##### Running install step. 04:27:51 INFO - ##### 04:27:51 INFO - Running pre-action listener: _resource_record_pre_action 04:27:51 INFO - Running main action method: install 04:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:27:51 INFO - Reading from file tmpfile_stdout 04:27:51 INFO - Detecting whether we're running mozinstall >=1.0... 04:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:27:51 INFO - Reading from file tmpfile_stdout 04:27:51 INFO - Output received: 04:27:51 INFO - Usage: mozinstall [options] installer 04:27:51 INFO - Options: 04:27:51 INFO - -h, --help show this help message and exit 04:27:51 INFO - -d DEST, --destination=DEST 04:27:51 INFO - Directory to install application into. [default: 04:27:51 INFO - "/builds/slave/test"] 04:27:51 INFO - --app=APP Application being installed. [default: firefox] 04:27:51 INFO - mkdir: /builds/slave/test/build/application 04:27:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:27:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:28:14 INFO - Reading from file tmpfile_stdout 04:28:14 INFO - Output received: 04:28:14 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:28:14 INFO - Running post-action listener: _resource_record_post_action 04:28:14 INFO - ##### 04:28:14 INFO - ##### Running run-tests step. 04:28:14 INFO - ##### 04:28:14 INFO - Running pre-action listener: _resource_record_pre_action 04:28:14 INFO - Running main action method: run_tests 04:28:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:28:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:28:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:28:14 INFO - 2016-05-21 04:28:14.470 screenresolution[1667:10262] starting screenresolution argv=screenresolution get 04:28:14 INFO - 2016-05-21 04:28:14.473 screenresolution[1667:10262] Display 0: 1600x1200x32@60 04:28:14 INFO - 2016-05-21 04:28:14.479 screenresolution[1668:10264] starting screenresolution argv=screenresolution list 04:28:14 INFO - Available Modes on Display 0 04:28:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:28:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:28:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:28:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:28:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:28:14 INFO - Intel Iris: 04:28:14 INFO - Chipset Model: Intel Iris 04:28:14 INFO - Type: GPU 04:28:14 INFO - Bus: Built-In 04:28:14 INFO - VRAM (Dynamic, Max): 1536 MB 04:28:14 INFO - Vendor: Intel (0x8086) 04:28:14 INFO - Device ID: 0x0a2e 04:28:14 INFO - Revision ID: 0x0009 04:28:14 INFO - Displays: 04:28:14 INFO - Display: 04:28:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:28:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:28:14 INFO - Display Serial Number: 1600x1200 60 04:28:14 INFO - Main Display: Yes 04:28:14 INFO - Mirror: Off 04:28:14 INFO - Online: Yes 04:28:14 INFO - Rotation: Supported 04:28:14 INFO - Return code: 0 04:28:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:28:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:28:14 INFO - Python 2.7.3 04:28:14 INFO - Return code: 0 04:28:14 INFO - grabbing minidump binary from tooltool 04:28:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:28:14 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 0x10a6ded30>, '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 0x10a542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6d5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:28:14 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:28:14 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:28:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:28:14 INFO - Return code: 0 04:28:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:28:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:28:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:28:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:28:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:28:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:28:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:28:16 INFO - Return code: 0 04:28:16 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/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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:28:16 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/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0167 --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:28:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y86ti3m1Zw/Render', 04:28:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:16 INFO - 'HOME': '/Users/cltbld', 04:28:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:16 INFO - 'LOGNAME': 'cltbld', 04:28:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:28:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:16 INFO - 'MOZ_NO_REMOTE': '1', 04:28:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:28:16 INFO - 'NO_EM_RESTART': '1', 04:28:16 INFO - 'PAGER': '/bin/cat', 04:28:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:16 INFO - 'PWD': '/builds/slave/test', 04:28:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:28:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:16 INFO - 'SHELL': '/bin/bash', 04:28:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TCdJXPdEqc/Listeners', 04:28:16 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 04:28:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:16 INFO - 'USER': 'cltbld', 04:28:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:16 INFO - 'XPC_FLAGS': '0x0', 04:28:16 INFO - 'XPC_SERVICE_NAME': '0', 04:28:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:16 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/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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:28:16 INFO - mozversion application_buildid: 20160521031436 04:28:16 INFO - mozversion application_changeset: ee9ba5e8d935736380d5c657c67cf2791741b501 04:28:16 INFO - mozversion application_display_name: Firefox 04:28:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:28:16 INFO - mozversion application_name: Firefox 04:28:16 INFO - mozversion application_remotingname: firefox 04:28:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:28:16 INFO - mozversion application_vendor: Mozilla 04:28:16 INFO - mozversion application_version: 47.0 04:28:16 INFO - mozversion platform_buildid: 20160521031436 04:28:16 INFO - mozversion platform_changeset: ee9ba5e8d935736380d5c657c67cf2791741b501 04:28:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:28:16 INFO - mozversion platform_version: 47.0 04:28:16 INFO - using testdate: 1463830096 04:28:16 INFO - actual date: 1463830096 04:28:16 INFO - starting webserver on 'localhost:49276' 04:28:16 INFO - SUITE-START | Running 2 tests 04:28:16 INFO - TEST-START | dromaeo_css 04:28:16 INFO - operating with platform_type : mac_ 04:28:16 INFO - Initialising browser for dromaeo_css test... 04:28:16 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpK5O4Hz/profile http://localhost:49276/getInfo.html) 04:28:18 INFO - PROCESS | 1686 | __metrics Screen width/height:1600/1200 04:28:18 INFO - PROCESS | 1686 | colorDepth:24 04:28:18 INFO - PROCESS | 1686 | Browser inner width/height: 1024/689 04:28:18 INFO - PROCESS | 1686 | __metrics 04:28:18 INFO - PROCESS | 1686 | 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:28:18 INFO - PROCESS | 1686 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:28:19 INFO - TEST-INFO | 1686: exit 0 04:28:19 INFO - Browser initialized. 04:28:19 INFO - Running cycle 1/1 for dromaeo_css test... 04:28:19 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpK5O4Hz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:28:31 INFO - PROCESS | 1690 | RSS: Main: 157089792 04:28:31 INFO - PROCESS | 1690 | 04:29:23 INFO - PROCESS | 1690 | 1463830163074 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:31:31 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 04:31:33 INFO - PROCESS | 1690 | RSS: Main: 280838144 04:31:33 INFO - PROCESS | 1690 | 04:34:35 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 04:34:36 INFO - PROCESS | 1690 | RSS: Main: 294072320 04:34:36 INFO - PROCESS | 1690 | 04:37:38 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 04:37:38 INFO - PROCESS | 1690 | RSS: Main: 294383616 04:37:38 INFO - PROCESS | 1690 | 04:40:40 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 04:40:41 INFO - PROCESS | 1690 | RSS: Main: 252227584 04:40:41 INFO - PROCESS | 1690 | 04:43:44 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 04:43:44 INFO - PROCESS | 1690 | RSS: Main: 255541248 04:43:44 INFO - PROCESS | 1690 | 04:46:47 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 04:46:47 INFO - PROCESS | 1690 | RSS: Main: 301633536 04:46:47 INFO - PROCESS | 1690 | 04:46:47 INFO - PROCESS | 1690 | __start_tp_report 04:46:47 INFO - PROCESS | 1690 | _x_x_mozilla_page_load 04:46:47 INFO - PROCESS | 1690 | _x_x_mozilla_page_load_details 04:46:47 INFO - PROCESS | 1690 | |i|pagename|runs| 04:46:47 INFO - PROCESS | 1690 | |0;dojo.html;3550.8934731958043;3595.8921232363023;3610.548681414824;3612.566492020958;3677.9816100919493;5728.4271572842745;5748.741306641201;5765.990951583477;5777.19119323295;5788.884222315556;5793.913091303634;5816.970915145418;5831.096180092082;5839.6496210227415;5843.503302219306;5220.843374698765;5230.8430747077555;5248.291480650101;5271.762770675329;5301.443348448415;5324.787008519655;5360.892782144355;5367.892642147174;5373.704446255454;5373.892522149555;5285.207218917174;5310.654807437523;5350.411454739976;5354.437784032679;5356.223347614594;5342.786288548454;5390.865228369283;5395.298611180541;5411.702356370437;5415.675059496442;5095.872603184913;5281.683099014072;5302.655327403745;5303.893922121536;5316.840494785162;5639.323281206241;5666.773329066832;5702.914456283159;5710.77156913723;5718.142278658193;6157.599756015889;6305.432511073979;6311.6213027218055;6314.4001319875;6335.461485773715;6252.093446450284;6272.247330320345;6283.403076707706;6288.717007734663;6303.086052522359;5811.128330750379;5889.381614930457;5930.851728706731;5932.614380065325;5934.228550288435;6336.239651241881;6342.556021078481;6372.044193370939;6375.171227740364;6381;5098.903735696769;6090.269167699905;6222.968885155546;6276.089966954766;6282.308946015934;5187.455317191716;6187.628742275478;6263.498920086382;6275.811725648238;6292.307846136921;6172.135900973867;6254.34329395416;6259.029850373199;6280.120783090371;6284.717187726564;6173.629582225081;6226.813195604139;6241.469475094668;6254;6266.561340706198;4577.153226653079;4663.836765713251;4907.263910413467;4940.530649588283;4955.702657840541;4371.278739008092;4437.955620443754;4453.153900758845;4486.484054333728;4521.864344069683;6000.099985002197;6034.2155519782145;6037;6041.395860414012;6053.788117415869;6198.938010619926;6248.718807653667;6259.18630578031;6260.217472815898;6261.7182226799905;5879.764809407576;5941.435563621449;5998.580099393088;6037.486813620803;6066.696665166724;5444.8094316698725;5449.754761035763;5452.509274165344;5463.426340234298;5467.425920278393;6446.580972236883;6498;6508.284088750139;6511.28375878653;6524.510661700391;6179.320274769681;6179.5056395489155;6243;6259.499240060875;6267.62394256346;6424.196975378078;6487.805365839033;6494;6495.317991610813;6501.772437964649;6277.2781130170615;6291.716872740646;6293.527985401021;6294.213223347082;6299.937000629935;6188.133661287334;6191.25704915413;6228.252609686867;6248.375162483897;6251.124842521959;6025.6083354581815;6194.5044396449075;6219.564630475823;6222.377762223741;6225.533085018551;6084.908726369196;6156.26124865019;6178.505719542336;6219.751209951552;6221.346758590283;4964.875878102932;5956.791512297222;5996.280446346466;6105.236845394325;6135.417135372132;6193.535484838565;6205.720742566686;6221.471174949999;6225.346338634378;6226.813195604139;6095.177151084724;6118.388161183846;6130.601510901777;6139.72371243304;6161.383861613982 04:46:47 INFO - PROCESS | 1690 | |1;ext.html;482.8985912958279;602.0607851751269;604.1934018085855;646.8350570604496;668.6656671664168;31358;31755.682443175618;31775.841120794394;31814.68185318152;31826.36347273056;32842.34315313692;33251;39435.80282098585;39687.80156099216;39743.60256397435;9594.76013099671;9771.364861283993;9775.902240977606;9778.266630002743;9788.510574471284;8630.352723545728;9433.198178154866;9530.666426675069;9684.661036863712;9697.806043879153;9634.56644450998;9722.076402741728;9727;9736.41581505112;9745.269104817167;9620.903790962106;9708.466034368143;9795.706128816075;9805.215582753433;9826.90173098267;9770.974047725029;9897.109260166619;9914.355566888129;9917.603295868157;9926.057024582653;40618.796906015574;40641.79679101615;40686.38970415446;40727;41215.58784412147;3205.8236796976157;3269.950950735717;3286.917827054319;3349.6650334966553;3370.9774135518737;28084.438311233866;29241;29406;29438.70561294381;29703.25741856449;2792.804503684743;2798.1046065259125;2819.8026138170335;2879.8704058317426;2912.4757543642136;427955.86022070004;428211;431694;432684;433306;46131.5386846134;46553.53446465492;46626;46667;60049;45749.77125114353;45819;45885.77057114693;45893.77053114713;45967.31049034267;16117.597060073358;16284;16292.51122466328;16298.266578004019;16306.184690765414;13063.934680326538;13104.737905242033;13132.212067275994;13137.67155821093;13216.933915330554;14645.414183432757;14687.77968330476;14694.191819450032;14711.632209194855;14849.183294918883;6264.780732674334;6357.332480089616;6393.392627700361;6410.935890641127;6415.486761059104;957.8084383123403;1033.2508931025097;1079.5843600213975;1094;1113.6325012745774;30081.548776768366;30155.849220753757;30270.697293027228;30330.037599220268;30450.238744031576;1106.4965440724452;1142.365986877291;1142.6800495861173;1156.4795841871191;1198.4007996002;14888.957772956006;15022.549323520312;15128.697426051418;15391.46129885471;15503.147326896904;547343.263283681;550320;550642;552900;553712;58969.705151473965;59418.40581594129;59466.70266648639;59482.702586487656;59525;1417.2630232279157;1481.733288008169;1499.0930487055393;1517.120070359189;1517.332373755544;28740.71259287464;29194.854025729735;29303.413931721672;29639.407211856073;29866.552001720425;46651.30023049589;46841;46985.53014469812;47116.29325560188;47168.764156180376;372845;386129;386339;386520;387687;29855.253618659706;33294.50058249177;33422.33155336831;34504.75719078252;34529.82735086409;46911;47552.524474756196;49006.754966224944;49242.50757492523;49261.753691231315;35898;37035.81482092572;37043;37359.81320093382;37789;36140.4701670891;36298.63701362952;36806.44790328201;36939.75192852856;36978.63021369752;35590.64409355873;36673.44989825208;37051;38412.42381364226;38427.42358864563;31806.558700820922;33264.50103248405;33495.49756753602;33650;33678.49482257817;25307.49385012326;25378.492430150924;25573;25851.22446326613;26389 04:46:47 INFO - PROCESS | 1690 | |2;jquery.html;355.3976010661928;381.12911996088934;383.60105490290084;387.80222086735756;390.9179072394797;20480.385588432353;20666.586668266627;21262.362129136152;21556.137754489842;21621.783782162154;12562.874371256285;13911.930440347784;20204.494887627818;21218.257360992367;21227.787722122775;5408.8377348679605;5482.945170548283;5501.394846566875;5550.195221692852;5551.666899985994;4158.916821663565;4401.405810215628;5890.734916928727;6148.907766383508;6156.415140561662;6130.509559235251;6137.416945390203;6139.539534534905;6142.539309551779;6154.7230374633245;6537.280899101119;6673.0991316172185;6680.098186744776;6690.498212634023;6698.866022679544;4014.397840323975;5586.8826234752805;5825.679587622679;5833.504152147073;5839.883202335929;11521.596744113916;13623.795643065361;13806.516771913035;22030.898455077182;25576;2147.24846303794;2155.91376344946;2285.325828880484;2410.7348191698898;2794.762445192161;9857.260705447121;10443.42561159136;10466;10468.267221294513;10587;1777.6089260362698;1804.909754512269;1823.7537932379091;1828.9359872404534;1873.653374125791;105232;110825.44587277093;113754;113857;120070.19894701673;11328.660140195807;11641.76716465666;11657.300561966309;11670.70823229426;11782;14334.49829255971;14628.561143165722;14731.337089830984;14749.262536873113;14761.92619036898;7456.888146677804;8025.799355016171;9049.592768325441;9083.364164508554;9156.221721153777;8627.22354988054;9087.36388452802;9209.907900921038;9230.723078307661;9311.9756826749;4607.493175750665;4640.95359046412;4646.395968524071;4652.488226295105;4689.648276379286;1754.1520646045985;1878.3238034307637;1957.628050670368;1960.3922783937026;1999.1103958738513;788.6490511722228;796.4862663581957;846.5047946951103;1030.1912998296302;1122.825961975895;17953.281868725106;20378.388648340573;20914.268000619904;21451.78548214529;22561.09755609757;932.5523748600709;975.1126474907942;1002.9147522460527;1033.7725700345911;1072.5924148823553;10959.945200274107;10990.890091098987;11163.995240428401;11182;11239.66281011571;692805;708746;714808;734673;737916;119067.40466297613;129794.96975168092;131453;133645.33177334245;133964.66035339521;1051.7475805806705;1075.8117329467311;1094.5731164845718;1095.0691911874842;1103.227740581596;18665.6266874659;18904.14931328066;19263.903680481508;19285.325013624457;19637.70543441878;35895.82052089723;49112.263316051;54420.455795441536;54647.726761365935;56846.71576642256;77530;77950;78894;79843.60078199572;81698;8136.633851476579;8260.545669988209;8331.250187482914;8338.70814521513;8369.246767790928;9630.759231019281;12903.548375806802;13548;13804.17174969505;13923.373448195058;11450.255733377304;11745.941270293593;11939.940300298444;12028.578999734968;12169.756604867676;11580;11722.238054526428;11746.060315174936;11896.988755955494;11991.76016479683;9407.952960235156;11984.801519847946;12098.516059357527;12146.939265303618;12439;8135.308498777434;8348.540830254151;8428.157184281523;8497.787555311166;8749.1250874912;8987.281017518713;11861.347625880659;11989.38253685774;12101.515939362327;12134 04:46:47 INFO - PROCESS | 1690 | |3;mootools.html;1508.0876069977655;1569.1526575649148;1589.4278059898431;1859.6931506301453;2004.5489764802921;8209.138040505743;8699.260562852158;8844;8849.292056635453;8864.601092950817;8491.065982741893;8754.737357879274;8764.51795151268;8773.561321933894;8774.736757897257;2184.486645638274;2527.128140791424;2543.36415896026;2547.248561674301;2553.8978440862343;2457.1768457566736;2562.551553478143;2568.0626571301527;2572.369769406498;2579.0328626764963;2543.5930251159816;2558.2708927955537;2582.122078493312;2582.586786114222;2590.261775394013;2533.9239822805253;2553.399951011511;2567.255495906185;2575.3304140923306;2578.1105518596055;2516.886740096682;2534.619807028942;2540.326813394452;2547.949041019188;2565.4740778140435;8887.333449991276;8926.196642302224;8937.50843703596;8946.821063578756;8954.417962832395;4871.780769865329;5533.557315414757;5675.091985282335;5704.030314846486;5705.343885453184;3533.2050288685095;3546.556680414948;3629.67332940034;3728.478013078144;3742.0644838790304;1064.8775390829999;1083.4203701019917;1084.1110289562562;1093.0763504838399;1112.9610463633815;12254.754904901853;12268.202566833128;12275.38623068843;12278.201916875463;12278.263304201688;4863.294822250754;4905.166121759309;4950.925736113961;5025.095482813055;5027.698338099725;10285.588576456857;10298;10305.072543471124;10308.948455257676;10323.535440905178;5640.379558248589;5648.548116150698;5651.01107306228;5652.18043383707;5658.830235092953;4595.2188128018315;4769.451513076041;4872.244802055687;5065.468125846806;5117.897642047138;3967.2462232175794;4026.3960405939342;4029.8992525187105;4055.4322394864744;4084.3056680363816;1326.9336533173303;1343.9395227214604;1344.5428554291589;1356.789697596874;1360.1227208450493;1144.456383217965;1146.2950285574311;1171.1918776044502;1253.1478594555608;1387.5282403982694;8269;8337.666493340199;8364.163583641708;8383.077861435237;8388.203120703645;515.4458956621646;520.8958208358267;522.4305506997315;522.8797376603402;525.136151031551;6138.938610613926;6180.44257238966;6207.503399728011;6213.96893015532;6399.296077431479;10590.205734569938;10982.670519884416;11108.666739997812;11115.388653623966;11133.443327833576;10659.680209593724;10663.73340666474;10759.30064545802;10938.835917461245;10996.12031037515;8461.661533538589;8477.067522572512;8480.024797148284;8504.447210931146;8601.096884827128;7867.252611001945;8069.515829050276;8072.233137851895;8083.312918402002;8084.959575202086;9460.574274157541;9664.71005869825;9738.464384458923;9863.161631261137;9880.308378413441;9818.607255709692;9952.701418957442;10289.485525723834;10322.793544129225;10536.947315263375;6902.206246281742;7084.61034643079;7537.698492060256;7633.5419874807685;8073.838523229385;8344.37417193725;8387.790305242417;8408.159184081544;8408.495490270603;8409.621567029377;7329.853402932018;7340.412766978736;7463.141738700119;7581.166071732216;7705.383569314554;7296.270372962661;7343.045404097433;7355.227701091522;7462.850742985219;7498.475106742476;7832.530048197126;7858;7879.290863822283;7889.723859664884;7891.84216315659;7003.929960700328;7082.256363081802;7119.074520312326;7126.144862616312;7140.07179066718;7211.350978411968;7302.342789149002;7311.195768465358;7314.305141011595;7314.96342518284 04:46:47 INFO - PROCESS | 1690 | |4;prototype.html;335.76160925742727;341.375283231686;342.4811410712772;347.5586005772668;350.35884331673043;6536.509761767868;6548.312427195141;6590.901136482957;6597.5711578747405;6611.206655201383;6534.575252608577;6554.180727409074;6561;6563.081168636395;6570.211574611041;4767.928481072787;4889.364382630253;4907.9263811042865;4974.955259395531;5027.296178535008;4970.105381031413;4975.27858460523;4979.253112033188;4985.551300382965;4990.925136122961;4926.704397736129;4933.284673722308;4933.629977751665;4935.333729946465;4945.307656928033;4767.451743049542;4794.304825800257;4919.385076865392;4932.284818701268;4937.382827146606;4213.115245798386;4215.494140703105;4215.599518045811;4218.282891908383;4219.472565929259;6307.117003619497;6321.87356252877;6326.6520341381665;6339.619622822645;6356.396142366468;5707.058335374658;5714.200011998323;5723.456271654228;5727.3699893011735;5728.68492232927;5651.886962260773;6686.531942764009;6919.930800692029;6974.51178417511;6975.895361569581;4666.486686464486;4715.221988371914;4722.716637001757;4725.149473094842;4735.644826637981;7295.416366690652;7345.375643070292;7356.080489938758;7358.484906056578;7374.594397308146;4808.269023151663;4809.711417314972;4813.133635945564;4818.855434336975;4822.95177048232;4871.29366241893;4877.975610121997;4880.243562247857;4885.60915126789;4885.9755701221975;5688.374278829326;5711.571632127607;5714.657120572696;5720.542356611461;5722.427757224327;6419.293877673452;6694.698738556776;6936.028955946172;6947.652617369111;6978.91826766852;1393.6515871032243;1665.925033373501;1694.2883988724766;1701.140923833456;1703.6592681463758;984.8821587498242;1046.26238501856;1093.797647435227;1102.2559772153763;1123.1464087293552;6002.069679199731;6488.15653964991;6513.413792758674;6520.18497687789;6638.83402914931;6814.897776533356;7243.384312333511;7289.271072892669;7294.598797066106;7326.120865496175;6623.105880706139;6637.402633762985;6652.900206496906;6660.300668429842;6675.833104172434;3734.7572407793405;3819.0452386903276;3861.4400911867624;3861.575226725114;3866.5553461352306;6490.643014634241;6766.763163289262;7648;7650;7672.73145439907;7421.591812450259;7427.368673662691;7463.067116610424;7469.439792015512;7471.887921681179;7633.961830190813;7638.083429988437;7638.503497272548;7639.159692433939;7654.770356889302;5912;5957.910631340446;6994.720211191496;7263.455240856852;7292.942523333981;5432.293801805898;6912.550684205712;7110.4667149962925;7125.714971401086;7166.964165179349;6134.35589263121;7541;7590.127135379281;7628.732994345171;7631.809204769926;4731.597814185695;4732.219183834766;4739.763011849463;4748.169070412595;4753.239481682914;5868.3251426086545;7209.459290553124;7227.205007449282;7230.819229519094;7238.420926325776;5714.742836572281;5735.799247026334;6632.867342653217;7003.124609423822;7006.194287656781;6925.653717314013;6968.547044442095;6969.059177011039;6975.058367120374;6979.057827193264;5558.888822223614;6823.761168359084;6828.624425656438;6831.11195544576;6840.7263709453055;6751;6984.126984126984;6991.720331186695;6996.650167491503;6996.685149168198;6699.196096468455;6812.727490900308;6848.452123830181;6862.176538815174;6918.619475928671 04:46:47 INFO - PROCESS | 1690 | |5;yui.html;627.634894105321;652.4877970792927;742.1391186223977;748.9925100748991;752.9548227106374;15376.846231537682;15745.212739363029;15754.921225393871;15756.133412662299;15774.763378549302;12061.638150855466;14829.406823727066;14831.85168148321;14835.480758173466;14849.257537123154;715.2382712411265;807.56391548564;914.3234006834945;919.2554031234688;938.1509733691021;940.9294302927308;951.7763325618474;953.8759961205034;954.3939598355059;954.4559601027418;986.4968865878418;997.3766396002499;997.9051990060933;998.3909815012836;999.2206079258154;851.8594431918726;864.8918885139358;865.169437340151;865.5066612031145;982.5774916785779;715.8389362393472;727.5561907236581;727.6143643868758;728.8979542864004;728.9817754556126;15379.615509612237;15483.845161548466;15509.991850529792;15510.767338489932;15520.767188492182;12890.419931103123;12909.54816581415;12921;13056.934715326457;13189.340532973312;8513.446625969293;10317.174626029899;10380.169586433067;10662.520186591544;10728.892711072867;3549.982250088759;3574.1779390740016;3628.419452887552;3692.7230457715777;3826.330392181357;79362.41275174465;79809.60095199545;82128.58935705283;82848.17151828525;82854.58572707158;9635.421874687541;9702.078302561245;9724.416535007922;9845.655402060893;10523.315984460985;21890.671639925415;22279.108835646715;22302.88848555747;22339.553208936057;22343.77656223417;7752.5736084514765;7756.961215193888;7817.413693972975;7852.882206766903;7965.8406831864195;4227.471566054243;4265.466816647919;4340.717853339523;4501.909961800746;4576.016156526296;3526.7883926964464;3605.2969670914445;3608.296382205497;3612.6387361263664;3624.3113808376324;650.4373716735084;662.6481976767437;665.1386454541381;683.8734834055715;685.8868286732733;564.4553006348893;574.1932584718477;578.6354596604112;579.5855962986485;597.919280897082;7615.809604759926;8006.439549231614;8042.83914321722;8067.515949043076;8150.551719655476;518.4141919630794;521.387369840436;560.1122221279318;561.9943800561942;590.3358721438382;3795.848166073326;4079.2249472600706;4155.127423241153;4187.602177793105;4224.281872081692;87749;89871.10128898628;90206;90506.54746726356;90679;63041.684791575746;79435.60282198552;80498;83299.3340133194;86064;65919.34080659132;67922;68483;68900;69855;8432.873506897524;8470.703525376583;8514.829703405774;8608.397412181088;8712.91287087121;48902;49019;49117.2632410507;49398.25902611392;49691.50308496869;53282;64475.00136958225;69822;70395;71796;10757.30077544957;10961.616343427942;11117.221794474633;11241.49413276388;11727.944484996391;11878.227915185485;12050.879491204976;12176.391180440765;12263.38683065861;12391.504339826666;11448.026917712088;11655.125865560287;11826.994705450134;12046.638600841989;12149.271043737404;11589.362585057566;11677;11796.587119450778;12105.75788484243;12140.028797695986;11268.04221641137;11389.601363952223;11395.544178232778;11656.592019279284;11851.644450666494;9358.11097945694;9458.101480359355;9550.665726699532;9566.66516671913;9687.515624218902;9653.276004299565;9676.75808104803;9685.225181985283;9689.70930872075;9730.902690973 04:46:47 INFO - PROCESS | 1690 | __end_tp_report 04:46:47 INFO - PROCESS | 1690 | __start_cc_report 04:46:47 INFO - PROCESS | 1690 | _x_x_mozilla_cycle_collect,1047 04:46:47 INFO - PROCESS | 1690 | __end_cc_report 04:46:47 INFO - PROCESS | 1690 | __startTimestamp1463831207783__endTimestamp 04:46:47 INFO - PROCESS | 1690 | 04:46:47 INFO - PROCESS | 1690 | ------- Summary: start ------- 04:46:47 INFO - PROCESS | 1690 | Number of tests: 6 04:46:47 INFO - PROCESS | 1690 | 04:46:47 INFO - PROCESS | 1690 | [#0] dojo.html Cycles:165 Average:5819.70 Median:6090.27 stddev:634.52 (10.4%) stddev-sans-first:611.00 04:46:47 INFO - PROCESS | 1690 | Values: 3550.9 3595.9 3610.5 3612.6 3678.0 5728.4 5748.7 5766.0 5777.2 5788.9 5793.9 5817.0 5831.1 5839.6 5843.5 5220.8 5230.8 5248.3 5271.8 5301.4 5324.8 5360.9 5367.9 5373.7 5373.9 5285.2 5310.7 5350.4 5354.4 5356.2 5342.8 5390.9 5395.3 5411.7 5415.7 5095.9 5281.7 5302.7 5303.9 5316.8 5639.3 5666.8 5702.9 5710.8 5718.1 6157.6 6305.4 6311.6 6314.4 6335.5 6252.1 6272.2 6283.4 6288.7 6303.1 5811.1 5889.4 5930.9 5932.6 5934.2 6336.2 6342.6 6372.0 6375.2 6381.0 5098.9 6090.3 6223.0 6276.1 6282.3 5187.5 6187.6 6263.5 6275.8 6292.3 6172.1 6254.3 6259.0 6280.1 6284.7 6173.6 6226.8 6241.5 6254.0 6266.6 4577.2 4663.8 4907.3 4940.5 4955.7 4371.3 4438.0 4453.2 4486.5 4521.9 6000.1 6034.2 6037.0 6041.4 6053.8 6198.9 6248.7 6259.2 6260.2 6261.7 5879.8 5941.4 5998.6 6037.5 6066.7 5444.8 5449.8 5452.5 5463.4 5467.4 6446.6 6498.0 6508.3 6511.3 6524.5 6179.3 6179.5 6243.0 6259.5 6267.6 6424.2 6487.8 6494.0 6495.3 6501.8 6277.3 6291.7 6293.5 6294.2 6299.9 6188.1 6191.3 6228.3 6248.4 6251.1 6025.6 6194.5 6219.6 6222.4 6225.5 6084.9 6156.3 6178.5 6219.8 6221.3 4964.9 5956.8 5996.3 6105.2 6135.4 6193.5 6205.7 6221.5 6225.3 6226.8 6095.2 6118.4 6130.6 6139.7 6161.4 04:46:47 INFO - PROCESS | 1690 | 04:46:47 INFO - PROCESS | 1690 | [#1] ext.html Cycles:180 Average:59477.03 Median:37047.00 stddev:122463.35 (330.6%) stddev-sans-first:122726.79 04:46:47 INFO - PROCESS | 1690 | Values: 482.9 602.1 604.2 646.8 668.7 31358.0 31755.7 31775.8 31814.7 31826.4 32842.3 33251.0 39435.8 39687.8 39743.6 9594.8 9771.4 9775.9 9778.3 9788.5 8630.4 9433.2 9530.7 9684.7 9697.8 9634.6 9722.1 9727.0 9736.4 9745.3 9620.9 9708.5 9795.7 9805.2 9826.9 9771.0 9897.1 9914.4 9917.6 9926.1 40618.8 40641.8 40686.4 40727.0 41215.6 3205.8 3270.0 3286.9 3349.7 3371.0 28084.4 29241.0 29406.0 29438.7 29703.3 2792.8 2798.1 2819.8 2879.9 2912.5 427955.9 428211.0 431694.0 432684.0 433306.0 46131.5 46553.5 46626.0 46667.0 60049.0 45749.8 45819.0 45885.8 45893.8 45967.3 16117.6 16284.0 16292.5 16298.3 16306.2 13063.9 13104.7 13132.2 13137.7 13216.9 14645.4 14687.8 14694.2 14711.6 14849.2 6264.8 6357.3 6393.4 6410.9 6415.5 957.8 1033.3 1079.6 1094.0 1113.6 30081.5 30155.8 30270.7 30330.0 30450.2 1106.5 1142.4 1142.7 1156.5 1198.4 14889.0 15022.5 15128.7 15391.5 15503.1 547343.3 550320.0 550642.0 552900.0 553712.0 58969.7 59418.4 59466.7 59482.7 59525.0 1417.3 1481.7 1499.1 1517.1 1517.3 28740.7 29194.9 29303.4 29639.4 29866.6 46651.3 46841.0 46985.5 47116.3 47168.8 372845.0 386129.0 386339.0 386520.0 387687.0 29855.3 33294.5 33422.3 34504.8 34529.8 46911.0 47552.5 49006.8 49242.5 49261.8 35898.0 37035.8 37043.0 37359.8 37789.0 36140.5 36298.6 36806.4 36939.8 36978.6 35590.6 36673.4 37051.0 38412.4 38427.4 31806.6 33264.5 33495.5 33650.0 33678.5 25307.5 25378.5 25573.0 25851.2 26389.0 04:46:47 INFO - PROCESS | 1690 | 04:46:47 INFO - PROCESS | 1690 | [#2] jquery.html Cycles:180 Average:38063.31 Median:11799.52 stddev:118822.62 (1007.0%) stddev-sans-first:119122.21 04:46:47 INFO - PROCESS | 1690 | Values: 355.4 381.1 383.6 387.8 390.9 20480.4 20666.6 21262.4 21556.1 21621.8 12562.9 13911.9 20204.5 21218.3 21227.8 5408.8 5482.9 5501.4 5550.2 5551.7 4158.9 4401.4 5890.7 6148.9 6156.4 6130.5 6137.4 6139.5 6142.5 6154.7 6537.3 6673.1 6680.1 6690.5 6698.9 4014.4 5586.9 5825.7 5833.5 5839.9 11521.6 13623.8 13806.5 22030.9 25576.0 2147.2 2155.9 2285.3 2410.7 2794.8 9857.3 10443.4 10466.0 10468.3 10587.0 1777.6 1804.9 1823.8 1828.9 1873.7 105232.0 110825.4 113754.0 113857.0 120070.2 11328.7 11641.8 11657.3 11670.7 11782.0 14334.5 14628.6 14731.3 14749.3 14761.9 7456.9 8025.8 9049.6 9083.4 9156.2 8627.2 9087.4 9209.9 9230.7 9312.0 4607.5 4641.0 4646.4 4652.5 4689.6 1754.2 1878.3 1957.6 1960.4 1999.1 788.6 796.5 846.5 1030.2 1122.8 17953.3 20378.4 20914.3 21451.8 22561.1 932.6 975.1 1002.9 1033.8 1072.6 10959.9 10990.9 11164.0 11182.0 11239.7 692805.0 708746.0 714808.0 734673.0 737916.0 119067.4 129795.0 131453.0 133645.3 133964.7 1051.7 1075.8 1094.6 1095.1 1103.2 18665.6 18904.1 19263.9 19285.3 19637.7 35895.8 49112.3 54420.5 54647.7 56846.7 77530.0 77950.0 78894.0 79843.6 81698.0 8136.6 8260.5 8331.3 8338.7 8369.2 9630.8 12903.5 13548.0 13804.2 13923.4 11450.3 11745.9 11939.9 12028.6 12169.8 11580.0 11722.2 11746.1 11897.0 11991.8 9408.0 11984.8 12098.5 12146.9 12439.0 8135.3 8348.5 8428.2 8497.8 8749.1 8987.3 11861.3 11989.4 12101.5 12134.0 04:46:47 INFO - PROCESS | 1690 | 04:46:47 INFO - PROCESS | 1690 | [#3] mootools.html Cycles:180 Average:6164.49 Median:521.66 stddev:3272.96 (627.4%) stddev-sans-first:3263.43 04:46:47 INFO - PROCESS | 1690 | Values: 1508.1 1569.2 1589.4 1859.7 2004.5 8209.1 8699.3 8844.0 8849.3 8864.6 8491.1 8754.7 8764.5 8773.6 8774.7 2184.5 2527.1 2543.4 2547.2 2553.9 2457.2 2562.6 2568.1 2572.4 2579.0 2543.6 2558.3 2582.1 2582.6 2590.3 2533.9 2553.4 2567.3 2575.3 2578.1 2516.9 2534.6 2540.3 2547.9 2565.5 8887.3 8926.2 8937.5 8946.8 8954.4 4871.8 5533.6 5675.1 5704.0 5705.3 3533.2 3546.6 3629.7 3728.5 3742.1 1064.9 1083.4 1084.1 1093.1 1113.0 12254.8 12268.2 12275.4 12278.2 12278.3 4863.3 4905.2 4950.9 5025.1 5027.7 10285.6 10298.0 10305.1 10308.9 10323.5 5640.4 5648.5 5651.0 5652.2 5658.8 4595.2 4769.5 4872.2 5065.5 5117.9 3967.2 4026.4 4029.9 4055.4 4084.3 1326.9 1343.9 1344.5 1356.8 1360.1 1144.5 1146.3 1171.2 1253.1 1387.5 8269.0 8337.7 8364.2 8383.1 8388.2 515.4 520.9 522.4 522.9 525.1 6138.9 6180.4 6207.5 6214.0 6399.3 10590.2 10982.7 11108.7 11115.4 11133.4 10659.7 10663.7 10759.3 10938.8 10996.1 8461.7 8477.1 8480.0 8504.4 8601.1 7867.3 8069.5 8072.2 8083.3 8085.0 9460.6 9664.7 9738.5 9863.2 9880.3 9818.6 9952.7 10289.5 10322.8 10536.9 6902.2 7084.6 7537.7 7633.5 8073.8 8344.4 8387.8 8408.2 8408.5 8409.6 7329.9 7340.4 7463.1 7581.2 7705.4 7296.3 7343.0 7355.2 7462.9 7498.5 7832.5 7858.0 7879.3 7889.7 7891.8 7003.9 7082.3 7119.1 7126.1 7140.1 7211.4 7302.3 7311.2 7314.3 7315.0 04:46:47 INFO - PROCESS | 1690 | 04:46:47 INFO - PROCESS | 1690 | [#4] prototype.html Cycles:180 Average:5712.63 Median:6489.40 stddev:1772.00 (27.3%) stddev-sans-first:1730.40 04:46:47 INFO - PROCESS | 1690 | Values: 335.8 341.4 342.5 347.6 350.4 6536.5 6548.3 6590.9 6597.6 6611.2 6534.6 6554.2 6561.0 6563.1 6570.2 4767.9 4889.4 4907.9 4975.0 5027.3 4970.1 4975.3 4979.3 4985.6 4990.9 4926.7 4933.3 4933.6 4935.3 4945.3 4767.5 4794.3 4919.4 4932.3 4937.4 4213.1 4215.5 4215.6 4218.3 4219.5 6307.1 6321.9 6326.7 6339.6 6356.4 5707.1 5714.2 5723.5 5727.4 5728.7 5651.9 6686.5 6919.9 6974.5 6975.9 4666.5 4715.2 4722.7 4725.1 4735.6 7295.4 7345.4 7356.1 7358.5 7374.6 4808.3 4809.7 4813.1 4818.9 4823.0 4871.3 4878.0 4880.2 4885.6 4886.0 5688.4 5711.6 5714.7 5720.5 5722.4 6419.3 6694.7 6936.0 6947.7 6978.9 1393.7 1665.9 1694.3 1701.1 1703.7 984.9 1046.3 1093.8 1102.3 1123.1 6002.1 6488.2 6513.4 6520.2 6638.8 6814.9 7243.4 7289.3 7294.6 7326.1 6623.1 6637.4 6652.9 6660.3 6675.8 3734.8 3819.0 3861.4 3861.6 3866.6 6490.6 6766.8 7648.0 7650.0 7672.7 7421.6 7427.4 7463.1 7469.4 7471.9 7634.0 7638.1 7638.5 7639.2 7654.8 5912.0 5957.9 6994.7 7263.5 7292.9 5432.3 6912.6 7110.5 7125.7 7167.0 6134.4 7541.0 7590.1 7628.7 7631.8 4731.6 4732.2 4739.8 4748.2 4753.2 5868.3 7209.5 7227.2 7230.8 7238.4 5714.7 5735.8 6632.9 7003.1 7006.2 6925.7 6968.5 6969.1 6975.1 6979.1 5558.9 6823.8 6828.6 6831.1 6840.7 6751.0 6984.1 6991.7 6996.7 6996.7 6699.2 6812.7 6848.5 6862.2 6918.6 04:46:47 INFO - PROCESS | 1690 | 04:46:47 INFO - PROCESS | 1690 | [#5] yui.html Cycles:180 Average:18290.59 Median:612.78 stddev:25367.55 (4139.8%) stddev-sans-first:25404.04 04:46:47 INFO - PROCESS | 1690 | Values: 627.6 652.5 742.1 749.0 753.0 15376.8 15745.2 15754.9 15756.1 15774.8 12061.6 14829.4 14831.9 14835.5 14849.3 715.2 807.6 914.3 919.3 938.2 940.9 951.8 953.9 954.4 954.5 986.5 997.4 997.9 998.4 999.2 851.9 864.9 865.2 865.5 982.6 715.8 727.6 727.6 728.9 729.0 15379.6 15483.8 15510.0 15510.8 15520.8 12890.4 12909.5 12921.0 13056.9 13189.3 8513.4 10317.2 10380.2 10662.5 10728.9 3550.0 3574.2 3628.4 3692.7 3826.3 79362.4 79809.6 82128.6 82848.2 82854.6 9635.4 9702.1 9724.4 9845.7 10523.3 21890.7 22279.1 22302.9 22339.6 22343.8 7752.6 7757.0 7817.4 7852.9 7965.8 4227.5 4265.5 4340.7 4501.9 4576.0 3526.8 3605.3 3608.3 3612.6 3624.3 650.4 662.6 665.1 683.9 685.9 564.5 574.2 578.6 579.6 597.9 7615.8 8006.4 8042.8 8067.5 8150.6 518.4 521.4 560.1 562.0 590.3 3795.8 4079.2 4155.1 4187.6 4224.3 87749.0 89871.1 90206.0 90506.5 90679.0 63041.7 79435.6 80498.0 83299.3 86064.0 65919.3 67922.0 68483.0 68900.0 69855.0 8432.9 8470.7 8514.8 8608.4 8712.9 48902.0 49019.0 49117.3 49398.3 49691.5 53282.0 64475.0 69822.0 70395.0 71796.0 10757.3 10961.6 11117.2 11241.5 11727.9 11878.2 12050.9 12176.4 12263.4 12391.5 11448.0 11655.1 11827.0 12046.6 12149.3 11589.4 11677.0 11796.6 12105.8 12140.0 11268.0 11389.6 11395.5 11656.6 11851.6 9358.1 9458.1 9550.7 9566.7 9687.5 9653.3 9676.8 9685.2 9689.7 9730.9 04:46:47 INFO - PROCESS | 1690 | -------- Summary: end -------- 04:46:47 INFO - PROCESS | 1690 | 04:46:48 INFO - TEST-INFO | 1690: exit 0 04:46:48 INFO - TEST-OK | dromaeo_css | took 1112100ms 04:46:48 INFO - TEST-START | kraken 04:46:48 INFO - operating with platform_type : mac_ 04:46:48 INFO - Initialising browser for kraken test... 04:46:48 INFO - TEST-INFO | started process 1697 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpqXeB44/profile http://localhost:49276/getInfo.html) 04:46:50 INFO - PROCESS | 1697 | __metrics Screen width/height:1600/1200 04:46:50 INFO - PROCESS | 1697 | colorDepth:24 04:46:50 INFO - PROCESS | 1697 | Browser inner width/height: 1024/689 04:46:50 INFO - PROCESS | 1697 | __metrics 04:46:50 INFO - PROCESS | 1697 | 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:50 INFO - PROCESS | 1697 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:46:51 INFO - TEST-INFO | 1697: exit 0 04:46:51 INFO - Browser initialized. 04:46:51 INFO - Running cycle 1/1 for kraken test... 04:46:51 INFO - TEST-INFO | started process 1700 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpqXeB44/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:47:03 INFO - PROCESS | 1700 | RSS: Main: 156712960 04:47:03 INFO - PROCESS | 1700 | 04:47:05 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 04:47:06 INFO - PROCESS | 1700 | RSS: Main: 213159936 04:47:06 INFO - PROCESS | 1700 | 04:47:08 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 04:47:09 INFO - PROCESS | 1700 | RSS: Main: 223019008 04:47:09 INFO - PROCESS | 1700 | 04:47:12 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 04:47:12 INFO - PROCESS | 1700 | RSS: Main: 213635072 04:47:12 INFO - PROCESS | 1700 | 04:47:14 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 04:47:15 INFO - PROCESS | 1700 | RSS: Main: 213962752 04:47:15 INFO - PROCESS | 1700 | 04:47:17 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:47:18 INFO - PROCESS | 1700 | RSS: Main: 213704704 04:47:18 INFO - PROCESS | 1700 | 04:47:20 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 04:47:21 INFO - PROCESS | 1700 | RSS: Main: 214859776 04:47:21 INFO - PROCESS | 1700 | 04:47:24 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 04:47:25 INFO - PROCESS | 1700 | RSS: Main: 215670784 04:47:25 INFO - PROCESS | 1700 | 04:47:27 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 04:47:28 INFO - PROCESS | 1700 | RSS: Main: 215330816 04:47:28 INFO - PROCESS | 1700 | 04:47:29 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:47:30 INFO - PROCESS | 1700 | RSS: Main: 249909248 04:47:30 INFO - PROCESS | 1700 | 04:47:32 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:47:32 INFO - PROCESS | 1700 | RSS: Main: 250650624 04:47:32 INFO - PROCESS | 1700 | 04:47:34 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:47:35 INFO - PROCESS | 1700 | RSS: Main: 256225280 04:47:35 INFO - PROCESS | 1700 | 04:47:38 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:47:38 INFO - PROCESS | 1700 | RSS: Main: 257421312 04:47:38 INFO - PROCESS | 1700 | 04:47:41 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:47:41 INFO - PROCESS | 1700 | RSS: Main: 257306624 04:47:41 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 04:47:43 INFO - PROCESS | 1700 | RSS: Main: 320430080 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | __start_tp_report 04:47:43 INFO - PROCESS | 1700 | _x_x_mozilla_page_load 04:47:43 INFO - PROCESS | 1700 | _x_x_mozilla_page_load_details 04:47:43 INFO - PROCESS | 1700 | |i|pagename|runs| 04:47:43 INFO - PROCESS | 1700 | |0;ai-astar;113;108;111;109;111;111;120;111;110;111 04:47:43 INFO - PROCESS | 1700 | |1;audio-beat-detection;155;118;154;146;116;134;118;148;148;118 04:47:43 INFO - PROCESS | 1700 | |2;audio-dft;185;184;182;182;183;188;184;184;183;183 04:47:43 INFO - PROCESS | 1700 | |3;audio-fft;73;72;76;117;55;58;58;58;116;74 04:47:43 INFO - PROCESS | 1700 | |4;audio-oscillator;92;93;131;80;77;83;118;76;79;117 04:47:43 INFO - PROCESS | 1700 | |5;imaging-gaussian-blur;87;86;87;85;87;84;89;84;90;128 04:47:43 INFO - PROCESS | 1700 | |6;imaging-darkroom;93;94;126;149;146;92;94;94;93;93 04:47:43 INFO - PROCESS | 1700 | |7;imaging-desaturate;85;86;84;84;87;84;86;85;85;84 04:47:43 INFO - PROCESS | 1700 | |8;json-parse-financial;57;58;57;58;57;57;57;56;53;53 04:47:43 INFO - PROCESS | 1700 | |9;json-stringify-tinderbox;61;63;75;72;69;66;65;65;68;64 04:47:43 INFO - PROCESS | 1700 | |10;stanford-crypto-aes;79;81;81;82;79;78;80;80;78;84 04:47:43 INFO - PROCESS | 1700 | |11;stanford-crypto-ccm;130;128;116;116;115;114;130;108;124;130 04:47:43 INFO - PROCESS | 1700 | |12;stanford-crypto-pbkdf2;135;123;132;134;135;133;135;136;147;133 04:47:43 INFO - PROCESS | 1700 | |13;stanford-crypto-sha256-iterative;65;66;65;64;66;68;87;93;94;89 04:47:43 INFO - PROCESS | 1700 | __end_tp_report 04:47:43 INFO - PROCESS | 1700 | __start_cc_report 04:47:43 INFO - PROCESS | 1700 | _x_x_mozilla_cycle_collect,1195 04:47:43 INFO - PROCESS | 1700 | __end_cc_report 04:47:43 INFO - PROCESS | 1700 | __startTimestamp1463831263804__endTimestamp 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | ------- Summary: start ------- 04:47:43 INFO - PROCESS | 1700 | Number of tests: 14 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#0] ai-astar Cycles:10 Average:111.50 Median:111.00 stddev:3.27 (2.9%) stddev-sans-first:3.43 04:47:43 INFO - PROCESS | 1700 | Values: 113.0 108.0 111.0 109.0 111.0 111.0 120.0 111.0 110.0 111.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:147.00 stddev:16.49 (11.2%) stddev-sans-first:15.91 04:47:43 INFO - PROCESS | 1700 | Values: 155.0 118.0 154.0 146.0 116.0 134.0 118.0 148.0 148.0 118.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.75 (1.0%) stddev-sans-first:1.80 04:47:43 INFO - PROCESS | 1700 | Values: 185.0 184.0 182.0 182.0 183.0 188.0 184.0 184.0 183.0 183.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#3] audio-fft Cycles:10 Average:75.70 Median:65.00 stddev:22.91 (35.2%) stddev-sans-first:24.27 04:47:43 INFO - PROCESS | 1700 | Values: 73.0 72.0 76.0 117.0 55.0 58.0 58.0 58.0 116.0 74.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:79.50 stddev:20.08 (25.3%) stddev-sans-first:21.28 04:47:43 INFO - PROCESS | 1700 | Values: 92.0 93.0 131.0 80.0 77.0 83.0 118.0 76.0 79.0 117.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:87.00 stddev:13.25 (15.2%) stddev-sans-first:13.99 04:47:43 INFO - PROCESS | 1700 | Values: 87.0 86.0 87.0 85.0 87.0 84.0 89.0 84.0 90.0 128.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#6] imaging-darkroom Cycles:10 Average:107.40 Median:93.00 stddev:23.49 (25.3%) stddev-sans-first:24.33 04:47:43 INFO - PROCESS | 1700 | Values: 93.0 94.0 126.0 149.0 146.0 92.0 94.0 94.0 93.0 93.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:1.05 (1.2%) stddev-sans-first:1.12 04:47:43 INFO - PROCESS | 1700 | Values: 85.0 86.0 84.0 84.0 87.0 84.0 86.0 85.0 85.0 84.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.83 (3.2%) stddev-sans-first:1.92 04:47:43 INFO - PROCESS | 1700 | Values: 57.0 58.0 57.0 58.0 57.0 57.0 57.0 56.0 53.0 53.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:67.00 stddev:4.26 (6.4%) stddev-sans-first:3.97 04:47:43 INFO - PROCESS | 1700 | Values: 61.0 63.0 75.0 72.0 69.0 66.0 65.0 65.0 68.0 64.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.50 stddev:1.87 (2.3%) stddev-sans-first:1.94 04:47:43 INFO - PROCESS | 1700 | Values: 79.0 81.0 81.0 82.0 79.0 78.0 80.0 80.0 78.0 84.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#11] stanford-crypto-ccm Cycles:10 Average:121.10 Median:126.00 stddev:8.20 (6.5%) stddev-sans-first:8.04 04:47:43 INFO - PROCESS | 1700 | Values: 130.0 128.0 116.0 116.0 115.0 114.0 130.0 108.0 124.0 130.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.30 Median:135.00 stddev:5.79 (4.3%) stddev-sans-first:6.14 04:47:43 INFO - PROCESS | 1700 | Values: 135.0 123.0 132.0 134.0 135.0 133.0 135.0 136.0 147.0 133.0 04:47:43 INFO - PROCESS | 1700 | 04:47:43 INFO - PROCESS | 1700 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:77.50 stddev:13.13 (16.9%) stddev-sans-first:13.35 04:47:43 INFO - PROCESS | 1700 | Values: 65.0 66.0 65.0 64.0 66.0 68.0 87.0 93.0 94.0 89.0 04:47:43 INFO - PROCESS | 1700 | -------- Summary: end -------- 04:47:43 INFO - PROCESS | 1700 | 04:47:44 INFO - TEST-INFO | 1700: exit 0 04:47:44 INFO - TEST-OK | kraken | took 56193ms 04:47:44 INFO - SUITE-END | took 1168s 04:47:44 INFO - Completed test suite (00:19:28) 04:47:44 INFO - javascript benchmark 04:47:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3550.8934731958043, 3595.8921232363023, 3610.548681414824, 3612.566492020958, 3677.9816100919493, 5728.4271572842745, 5748.741306641201, 5765.990951583477, 5777.19119323295, 5788.884222315556, 5793.913091303634, 5816.970915145418, 5831.096180092082, 5839.6496210227415, 5843.503302219306, 5220.843374698765, 5230.8430747077555, 5248.291480650101, 5271.762770675329, 5301.443348448415, 5324.787008519655, 5360.892782144355, 5367.892642147174, 5373.704446255454, 5373.892522149555, 5285.207218917174, 5310.654807437523, 5350.411454739976, 5354.437784032679, 5356.223347614594, 5342.786288548454, 5390.865228369283, 5395.298611180541, 5411.702356370437, 5415.675059496442, 5095.872603184913, 5281.683099014072, 5302.655327403745, 5303.893922121536, 5316.840494785162, 5639.323281206241, 5666.773329066832, 5702.914456283159, 5710.77156913723, 5718.142278658193, 6157.599756015889, 6305.432511073979, 6311.6213027218055, 6314.4001319875, 6335.461485773715, 6252.093446450284, 6272.247330320345, 6283.403076707706, 6288.717007734663, 6303.086052522359, 5811.128330750379, 5889.381614930457, 5930.851728706731, 5932.614380065325, 5934.228550288435, 6336.239651241881, 6342.556021078481, 6372.044193370939, 6375.171227740364, 6381.0, 5098.903735696769, 6090.269167699905, 6222.968885155546, 6276.089966954766, 6282.308946015934, 5187.455317191716, 6187.628742275478, 6263.498920086382, 6275.811725648238, 6292.307846136921, 6172.135900973867, 6254.34329395416, 6259.029850373199, 6280.120783090371, 6284.717187726564, 6173.629582225081, 6226.813195604139, 6241.469475094668, 6254.0, 6266.561340706198, 4577.153226653079, 4663.836765713251, 4907.263910413467, 4940.530649588283, 4955.702657840541, 4371.278739008092, 4437.955620443754, 4453.153900758845, 4486.484054333728, 4521.864344069683, 6000.099985002197, 6034.2155519782145, 6037.0, 6041.395860414012, 6053.788117415869, 6198.938010619926, 6248.718807653667, 6259.18630578031, 6260.217472815898, 6261.7182226799905, 5879.764809407576, 5941.435563621449, 5998.580099393088, 6037.486813620803, 6066.696665166724, 5444.8094316698725, 5449.754761035763, 5452.509274165344, 5463.426340234298, 5467.425920278393, 6446.580972236883, 6498.0, 6508.284088750139, 6511.28375878653, 6524.510661700391, 6179.320274769681, 6179.5056395489155, 6243.0, 6259.499240060875, 6267.62394256346, 6424.196975378078, 6487.805365839033, 6494.0, 6495.317991610813, 6501.772437964649, 6277.2781130170615, 6291.716872740646, 6293.527985401021, 6294.213223347082, 6299.937000629935, 6188.133661287334, 6191.25704915413, 6228.252609686867, 6248.375162483897, 6251.124842521959, 6025.6083354581815, 6194.5044396449075, 6219.564630475823, 6222.377762223741, 6225.533085018551, 6084.908726369196, 6156.26124865019, 6178.505719542336, 6219.751209951552, 6221.346758590283, 4964.875878102932, 5956.791512297222, 5996.280446346466, 6105.236845394325, 6135.417135372132, 6193.535484838565, 6205.720742566686, 6221.471174949999, 6225.346338634378, 6226.813195604139, 6095.177151084724, 6118.388161183846, 6130.601510901777, 6139.72371243304, 6161.383861613982], "unit": "score", "name": "dojo.html", "value": 5781.547677512187}, {"lowerIsBetter": false, "replicates": [482.8985912958279, 602.0607851751269, 604.1934018085855, 646.8350570604496, 668.6656671664168, 31358.0, 31755.682443175618, 31775.841120794394, 31814.68185318152, 31826.36347273056, 32842.34315313692, 33251.0, 39435.80282098585, 39687.80156099216, 39743.60256397435, 9594.76013099671, 9771.364861283993, 9775.902240977606, 9778.266630002743, 9788.510574471284, 8630.352723545728, 9433.198178154866, 9530.666426675069, 9684.661036863712, 9697.806043879153, 9634.56644450998, 9722.076402741728, 9727.0, 9736.41581505112, 9745.269104817167, 9620.903790962106, 9708.466034368143, 9795.706128816075, 9805.215582753433, 9826.90173098267, 9770.974047725029, 9897.109260166619, 9914.355566888129, 9917.603295868157, 9926.057024582653, 40618.796906015574, 40641.79679101615, 40686.38970415446, 40727.0, 41215.58784412147, 3205.8236796976157, 3269.950950735717, 3286.917827054319, 3349.6650334966553, 3370.9774135518737, 28084.438311233866, 29241.0, 29406.0, 29438.70561294381, 29703.25741856449, 2792.804503684743, 2798.1046065259125, 2819.8026138170335, 2879.8704058317426, 2912.4757543642136, 427955.86022070004, 428211.0, 431694.0, 432684.0, 433306.0, 46131.5386846134, 46553.53446465492, 46626.0, 46667.0, 60049.0, 45749.77125114353, 45819.0, 45885.77057114693, 45893.77053114713, 45967.31049034267, 16117.597060073358, 16284.0, 16292.51122466328, 16298.266578004019, 16306.184690765414, 13063.934680326538, 13104.737905242033, 13132.212067275994, 13137.67155821093, 13216.933915330554, 14645.414183432757, 14687.77968330476, 14694.191819450032, 14711.632209194855, 14849.183294918883, 6264.780732674334, 6357.332480089616, 6393.392627700361, 6410.935890641127, 6415.486761059104, 957.8084383123403, 1033.2508931025097, 1079.5843600213975, 1094.0, 1113.6325012745774, 30081.548776768366, 30155.849220753757, 30270.697293027228, 30330.037599220268, 30450.238744031576, 1106.4965440724452, 1142.365986877291, 1142.6800495861173, 1156.4795841871191, 1198.4007996002, 14888.957772956006, 15022.549323520312, 15128.697426051418, 15391.46129885471, 15503.147326896904, 547343.263283681, 550320.0, 550642.0, 552900.0, 553712.0, 58969.705151473965, 59418.40581594129, 59466.70266648639, 59482.702586487656, 59525.0, 1417.2630232279157, 1481.733288008169, 1499.0930487055393, 1517.120070359189, 1517.332373755544, 28740.71259287464, 29194.854025729735, 29303.413931721672, 29639.407211856073, 29866.552001720425, 46651.30023049589, 46841.0, 46985.53014469812, 47116.29325560188, 47168.764156180376, 372845.0, 386129.0, 386339.0, 386520.0, 387687.0, 29855.253618659706, 33294.50058249177, 33422.33155336831, 34504.75719078252, 34529.82735086409, 46911.0, 47552.524474756196, 49006.754966224944, 49242.50757492523, 49261.753691231315, 35898.0, 37035.81482092572, 37043.0, 37359.81320093382, 37789.0, 36140.4701670891, 36298.63701362952, 36806.44790328201, 36939.75192852856, 36978.63021369752, 35590.64409355873, 36673.44989825208, 37051.0, 38412.42381364226, 38427.42358864563, 31806.558700820922, 33264.50103248405, 33495.49756753602, 33650.0, 33678.49482257817, 25307.49385012326, 25378.492430150924, 25573.0, 25851.22446326613, 26389.0], "unit": "score", "name": "ext.html", "value": 19096.625408997672}, {"lowerIsBetter": false, "replicates": [355.3976010661928, 381.12911996088934, 383.60105490290084, 387.80222086735756, 390.9179072394797, 20480.385588432353, 20666.586668266627, 21262.362129136152, 21556.137754489842, 21621.783782162154, 12562.874371256285, 13911.930440347784, 20204.494887627818, 21218.257360992367, 21227.787722122775, 5408.8377348679605, 5482.945170548283, 5501.394846566875, 5550.195221692852, 5551.666899985994, 4158.916821663565, 4401.405810215628, 5890.734916928727, 6148.907766383508, 6156.415140561662, 6130.509559235251, 6137.416945390203, 6139.539534534905, 6142.539309551779, 6154.7230374633245, 6537.280899101119, 6673.0991316172185, 6680.098186744776, 6690.498212634023, 6698.866022679544, 4014.397840323975, 5586.8826234752805, 5825.679587622679, 5833.504152147073, 5839.883202335929, 11521.596744113916, 13623.795643065361, 13806.516771913035, 22030.898455077182, 25576.0, 2147.24846303794, 2155.91376344946, 2285.325828880484, 2410.7348191698898, 2794.762445192161, 9857.260705447121, 10443.42561159136, 10466.0, 10468.267221294513, 10587.0, 1777.6089260362698, 1804.909754512269, 1823.7537932379091, 1828.9359872404534, 1873.653374125791, 105232.0, 110825.44587277093, 113754.0, 113857.0, 120070.19894701673, 11328.660140195807, 11641.76716465666, 11657.300561966309, 11670.70823229426, 11782.0, 14334.49829255971, 14628.561143165722, 14731.337089830984, 14749.262536873113, 14761.92619036898, 7456.888146677804, 8025.799355016171, 9049.592768325441, 9083.364164508554, 9156.221721153777, 8627.22354988054, 9087.36388452802, 9209.907900921038, 9230.723078307661, 9311.9756826749, 4607.493175750665, 4640.95359046412, 4646.395968524071, 4652.488226295105, 4689.648276379286, 1754.1520646045985, 1878.3238034307637, 1957.628050670368, 1960.3922783937026, 1999.1103958738513, 788.6490511722228, 796.4862663581957, 846.5047946951103, 1030.1912998296302, 1122.825961975895, 17953.281868725106, 20378.388648340573, 20914.268000619904, 21451.78548214529, 22561.09755609757, 932.5523748600709, 975.1126474907942, 1002.9147522460527, 1033.7725700345911, 1072.5924148823553, 10959.945200274107, 10990.890091098987, 11163.995240428401, 11182.0, 11239.66281011571, 692805.0, 708746.0, 714808.0, 734673.0, 737916.0, 119067.40466297613, 129794.96975168092, 131453.0, 133645.33177334245, 133964.66035339521, 1051.7475805806705, 1075.8117329467311, 1094.5731164845718, 1095.0691911874842, 1103.227740581596, 18665.6266874659, 18904.14931328066, 19263.903680481508, 19285.325013624457, 19637.70543441878, 35895.82052089723, 49112.263316051, 54420.455795441536, 54647.726761365935, 56846.71576642256, 77530.0, 77950.0, 78894.0, 79843.60078199572, 81698.0, 8136.633851476579, 8260.545669988209, 8331.250187482914, 8338.70814521513, 8369.246767790928, 9630.759231019281, 12903.548375806802, 13548.0, 13804.17174969505, 13923.373448195058, 11450.255733377304, 11745.941270293593, 11939.940300298444, 12028.578999734968, 12169.756604867676, 11580.0, 11722.238054526428, 11746.060315174936, 11896.988755955494, 11991.76016479683, 9407.952960235156, 11984.801519847946, 12098.516059357527, 12146.939265303618, 12439.0, 8135.308498777434, 8348.540830254151, 8428.157184281523, 8497.787555311166, 8749.1250874912, 8987.281017518713, 11861.347625880659, 11989.38253685774, 12101.515939362327, 12134.0], "unit": "score", "name": "jquery.html", "value": 9628.953475960418}, {"lowerIsBetter": false, "replicates": [1508.0876069977655, 1569.1526575649148, 1589.4278059898431, 1859.6931506301453, 2004.5489764802921, 8209.138040505743, 8699.260562852158, 8844.0, 8849.292056635453, 8864.601092950817, 8491.065982741893, 8754.737357879274, 8764.51795151268, 8773.561321933894, 8774.736757897257, 2184.486645638274, 2527.128140791424, 2543.36415896026, 2547.248561674301, 2553.8978440862343, 2457.1768457566736, 2562.551553478143, 2568.0626571301527, 2572.369769406498, 2579.0328626764963, 2543.5930251159816, 2558.2708927955537, 2582.122078493312, 2582.586786114222, 2590.261775394013, 2533.9239822805253, 2553.399951011511, 2567.255495906185, 2575.3304140923306, 2578.1105518596055, 2516.886740096682, 2534.619807028942, 2540.326813394452, 2547.949041019188, 2565.4740778140435, 8887.333449991276, 8926.196642302224, 8937.50843703596, 8946.821063578756, 8954.417962832395, 4871.780769865329, 5533.557315414757, 5675.091985282335, 5704.030314846486, 5705.343885453184, 3533.2050288685095, 3546.556680414948, 3629.67332940034, 3728.478013078144, 3742.0644838790304, 1064.8775390829999, 1083.4203701019917, 1084.1110289562562, 1093.0763504838399, 1112.9610463633815, 12254.754904901853, 12268.202566833128, 12275.38623068843, 12278.201916875463, 12278.263304201688, 4863.294822250754, 4905.166121759309, 4950.925736113961, 5025.095482813055, 5027.698338099725, 10285.588576456857, 10298.0, 10305.072543471124, 10308.948455257676, 10323.535440905178, 5640.379558248589, 5648.548116150698, 5651.01107306228, 5652.18043383707, 5658.830235092953, 4595.2188128018315, 4769.451513076041, 4872.244802055687, 5065.468125846806, 5117.897642047138, 3967.2462232175794, 4026.3960405939342, 4029.8992525187105, 4055.4322394864744, 4084.3056680363816, 1326.9336533173303, 1343.9395227214604, 1344.5428554291589, 1356.789697596874, 1360.1227208450493, 1144.456383217965, 1146.2950285574311, 1171.1918776044502, 1253.1478594555608, 1387.5282403982694, 8269.0, 8337.666493340199, 8364.163583641708, 8383.077861435237, 8388.203120703645, 515.4458956621646, 520.8958208358267, 522.4305506997315, 522.8797376603402, 525.136151031551, 6138.938610613926, 6180.44257238966, 6207.503399728011, 6213.96893015532, 6399.296077431479, 10590.205734569938, 10982.670519884416, 11108.666739997812, 11115.388653623966, 11133.443327833576, 10659.680209593724, 10663.73340666474, 10759.30064545802, 10938.835917461245, 10996.12031037515, 8461.661533538589, 8477.067522572512, 8480.024797148284, 8504.447210931146, 8601.096884827128, 7867.252611001945, 8069.515829050276, 8072.233137851895, 8083.312918402002, 8084.959575202086, 9460.574274157541, 9664.71005869825, 9738.464384458923, 9863.161631261137, 9880.308378413441, 9818.607255709692, 9952.701418957442, 10289.485525723834, 10322.793544129225, 10536.947315263375, 6902.206246281742, 7084.61034643079, 7537.698492060256, 7633.5419874807685, 8073.838523229385, 8344.37417193725, 8387.790305242417, 8408.159184081544, 8408.495490270603, 8409.621567029377, 7329.853402932018, 7340.412766978736, 7463.141738700119, 7581.166071732216, 7705.383569314554, 7296.270372962661, 7343.045404097433, 7355.227701091522, 7462.850742985219, 7498.475106742476, 7832.530048197126, 7858.0, 7879.290863822283, 7889.723859664884, 7891.84216315659, 7003.929960700328, 7082.256363081802, 7119.074520312326, 7126.144862616312, 7140.07179066718, 7211.350978411968, 7302.342789149002, 7311.195768465358, 7314.305141011595, 7314.96342518284], "unit": "score", "name": "mootools.html", "value": 4938.347063220384}, {"lowerIsBetter": false, "replicates": [335.76160925742727, 341.375283231686, 342.4811410712772, 347.5586005772668, 350.35884331673043, 6536.509761767868, 6548.312427195141, 6590.901136482957, 6597.5711578747405, 6611.206655201383, 6534.575252608577, 6554.180727409074, 6561.0, 6563.081168636395, 6570.211574611041, 4767.928481072787, 4889.364382630253, 4907.9263811042865, 4974.955259395531, 5027.296178535008, 4970.105381031413, 4975.27858460523, 4979.253112033188, 4985.551300382965, 4990.925136122961, 4926.704397736129, 4933.284673722308, 4933.629977751665, 4935.333729946465, 4945.307656928033, 4767.451743049542, 4794.304825800257, 4919.385076865392, 4932.284818701268, 4937.382827146606, 4213.115245798386, 4215.494140703105, 4215.599518045811, 4218.282891908383, 4219.472565929259, 6307.117003619497, 6321.87356252877, 6326.6520341381665, 6339.619622822645, 6356.396142366468, 5707.058335374658, 5714.200011998323, 5723.456271654228, 5727.3699893011735, 5728.68492232927, 5651.886962260773, 6686.531942764009, 6919.930800692029, 6974.51178417511, 6975.895361569581, 4666.486686464486, 4715.221988371914, 4722.716637001757, 4725.149473094842, 4735.644826637981, 7295.416366690652, 7345.375643070292, 7356.080489938758, 7358.484906056578, 7374.594397308146, 4808.269023151663, 4809.711417314972, 4813.133635945564, 4818.855434336975, 4822.95177048232, 4871.29366241893, 4877.975610121997, 4880.243562247857, 4885.60915126789, 4885.9755701221975, 5688.374278829326, 5711.571632127607, 5714.657120572696, 5720.542356611461, 5722.427757224327, 6419.293877673452, 6694.698738556776, 6936.028955946172, 6947.652617369111, 6978.91826766852, 1393.6515871032243, 1665.925033373501, 1694.2883988724766, 1701.140923833456, 1703.6592681463758, 984.8821587498242, 1046.26238501856, 1093.797647435227, 1102.2559772153763, 1123.1464087293552, 6002.069679199731, 6488.15653964991, 6513.413792758674, 6520.18497687789, 6638.83402914931, 6814.897776533356, 7243.384312333511, 7289.271072892669, 7294.598797066106, 7326.120865496175, 6623.105880706139, 6637.402633762985, 6652.900206496906, 6660.300668429842, 6675.833104172434, 3734.7572407793405, 3819.0452386903276, 3861.4400911867624, 3861.575226725114, 3866.5553461352306, 6490.643014634241, 6766.763163289262, 7648.0, 7650.0, 7672.73145439907, 7421.591812450259, 7427.368673662691, 7463.067116610424, 7469.439792015512, 7471.887921681179, 7633.961830190813, 7638.083429988437, 7638.503497272548, 7639.159692433939, 7654.770356889302, 5912.0, 5957.910631340446, 6994.720211191496, 7263.455240856852, 7292.942523333981, 5432.293801805898, 6912.550684205712, 7110.4667149962925, 7125.714971401086, 7166.964165179349, 6134.35589263121, 7541.0, 7590.127135379281, 7628.732994345171, 7631.809204769926, 4731.597814185695, 4732.219183834766, 4739.763011849463, 4748.169070412595, 4753.239481682914, 5868.3251426086545, 7209.459290553124, 7227.205007449282, 7230.819229519094, 7238.420926325776, 5714.742836572281, 5735.799247026334, 6632.867342653217, 7003.124609423822, 7006.194287656781, 6925.653717314013, 6968.547044442095, 6969.059177011039, 6975.058367120374, 6979.057827193264, 5558.888822223614, 6823.761168359084, 6828.624425656438, 6831.11195544576, 6840.7263709453055, 6751.0, 6984.126984126984, 6991.720331186695, 6996.650167491503, 6996.685149168198, 6699.196096468455, 6812.727490900308, 6848.452123830181, 6862.176538815174, 6918.619475928671], "unit": "score", "name": "prototype.html", "value": 5127.215842218548}, {"lowerIsBetter": false, "replicates": [627.634894105321, 652.4877970792927, 742.1391186223977, 748.9925100748991, 752.9548227106374, 15376.846231537682, 15745.212739363029, 15754.921225393871, 15756.133412662299, 15774.763378549302, 12061.638150855466, 14829.406823727066, 14831.85168148321, 14835.480758173466, 14849.257537123154, 715.2382712411265, 807.56391548564, 914.3234006834945, 919.2554031234688, 938.1509733691021, 940.9294302927308, 951.7763325618474, 953.8759961205034, 954.3939598355059, 954.4559601027418, 986.4968865878418, 997.3766396002499, 997.9051990060933, 998.3909815012836, 999.2206079258154, 851.8594431918726, 864.8918885139358, 865.169437340151, 865.5066612031145, 982.5774916785779, 715.8389362393472, 727.5561907236581, 727.6143643868758, 728.8979542864004, 728.9817754556126, 15379.615509612237, 15483.845161548466, 15509.991850529792, 15510.767338489932, 15520.767188492182, 12890.419931103123, 12909.54816581415, 12921.0, 13056.934715326457, 13189.340532973312, 8513.446625969293, 10317.174626029899, 10380.169586433067, 10662.520186591544, 10728.892711072867, 3549.982250088759, 3574.1779390740016, 3628.419452887552, 3692.7230457715777, 3826.330392181357, 79362.41275174465, 79809.60095199545, 82128.58935705283, 82848.17151828525, 82854.58572707158, 9635.421874687541, 9702.078302561245, 9724.416535007922, 9845.655402060893, 10523.315984460985, 21890.671639925415, 22279.108835646715, 22302.88848555747, 22339.553208936057, 22343.77656223417, 7752.5736084514765, 7756.961215193888, 7817.413693972975, 7852.882206766903, 7965.8406831864195, 4227.471566054243, 4265.466816647919, 4340.717853339523, 4501.909961800746, 4576.016156526296, 3526.7883926964464, 3605.2969670914445, 3608.296382205497, 3612.6387361263664, 3624.3113808376324, 650.4373716735084, 662.6481976767437, 665.1386454541381, 683.8734834055715, 685.8868286732733, 564.4553006348893, 574.1932584718477, 578.6354596604112, 579.5855962986485, 597.919280897082, 7615.809604759926, 8006.439549231614, 8042.83914321722, 8067.515949043076, 8150.551719655476, 518.4141919630794, 521.387369840436, 560.1122221279318, 561.9943800561942, 590.3358721438382, 3795.848166073326, 4079.2249472600706, 4155.127423241153, 4187.602177793105, 4224.281872081692, 87749.0, 89871.10128898628, 90206.0, 90506.54746726356, 90679.0, 63041.684791575746, 79435.60282198552, 80498.0, 83299.3340133194, 86064.0, 65919.34080659132, 67922.0, 68483.0, 68900.0, 69855.0, 8432.873506897524, 8470.703525376583, 8514.829703405774, 8608.397412181088, 8712.91287087121, 48902.0, 49019.0, 49117.2632410507, 49398.25902611392, 49691.50308496869, 53282.0, 64475.00136958225, 69822.0, 70395.0, 71796.0, 10757.30077544957, 10961.616343427942, 11117.221794474633, 11241.49413276388, 11727.944484996391, 11878.227915185485, 12050.879491204976, 12176.391180440765, 12263.38683065861, 12391.504339826666, 11448.026917712088, 11655.125865560287, 11826.994705450134, 12046.638600841989, 12149.271043737404, 11589.362585057566, 11677.0, 11796.587119450778, 12105.75788484243, 12140.028797695986, 11268.04221641137, 11389.601363952223, 11395.544178232778, 11656.592019279284, 11851.644450666494, 9358.11097945694, 9458.101480359355, 9550.665726699532, 9566.66516671913, 9687.515624218902, 9653.276004299565, 9676.75808104803, 9685.225181985283, 9689.70930872075, 9730.902690973], "unit": "score", "name": "yui.html", "value": 6993.765416817831}], "name": "dromaeo_css", "value": 7570.61685181443}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 108.0, 111.0, 109.0, 111.0, 111.0, 120.0, 111.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.5}, {"lowerIsBetter": true, "replicates": [155.0, 118.0, 154.0, 146.0, 116.0, 134.0, 118.0, 148.0, 148.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 135.5}, {"lowerIsBetter": true, "replicates": [185.0, 184.0, 182.0, 182.0, 183.0, 188.0, 184.0, 184.0, 183.0, 183.0], "unit": "score", "name": "audio-dft", "value": 183.8}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 76.0, 117.0, 55.0, 58.0, 58.0, 58.0, 116.0, 74.0], "unit": "score", "name": "audio-fft", "value": 75.7}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 131.0, 80.0, 77.0, 83.0, 118.0, 76.0, 79.0, 117.0], "unit": "score", "name": "audio-oscillator", "value": 94.6}, {"lowerIsBetter": true, "replicates": [87.0, 86.0, 87.0, 85.0, 87.0, 84.0, 89.0, 84.0, 90.0, 128.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 90.7}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 126.0, 149.0, 146.0, 92.0, 94.0, 94.0, 93.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 107.4}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 84.0, 84.0, 87.0, 84.0, 86.0, 85.0, 85.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 85.0}, {"lowerIsBetter": true, "replicates": [57.0, 58.0, 57.0, 58.0, 57.0, 57.0, 57.0, 56.0, 53.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 56.3}, {"lowerIsBetter": true, "replicates": [61.0, 63.0, 75.0, 72.0, 69.0, 66.0, 65.0, 65.0, 68.0, 64.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.8}, {"lowerIsBetter": true, "replicates": [79.0, 81.0, 81.0, 82.0, 79.0, 78.0, 80.0, 80.0, 78.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.2}, {"lowerIsBetter": true, "replicates": [130.0, 128.0, 116.0, 116.0, 115.0, 114.0, 130.0, 108.0, 124.0, 130.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.1}, {"lowerIsBetter": true, "replicates": [135.0, 123.0, 132.0, 134.0, 135.0, 133.0, 135.0, 136.0, 147.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 134.3}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 65.0, 64.0, 66.0, 68.0, 87.0, 93.0, 94.0, 89.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 75.7}], "name": "kraken", "value": 1418.6}]} 04:47:45 INFO - Return code: 0 04:47:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:47:45 INFO - # TBPL SUCCESS # 04:47:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:47:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:47:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:47:45 INFO - 2016-05-21 04:47:45.085 screenresolution[1704:12411] starting screenresolution argv=screenresolution get 04:47:45 INFO - 2016-05-21 04:47:45.088 screenresolution[1704:12411] Display 0: 1600x1200x32@60 04:47:45 INFO - 2016-05-21 04:47:45.094 screenresolution[1705:12415] starting screenresolution argv=screenresolution list 04:47:45 INFO - Available Modes on Display 0 04:47:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:47:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:47:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:47:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:47:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:47:45 INFO - Intel Iris: 04:47:45 INFO - Chipset Model: Intel Iris 04:47:45 INFO - Type: GPU 04:47:45 INFO - Bus: Built-In 04:47:45 INFO - VRAM (Dynamic, Max): 1536 MB 04:47:45 INFO - Vendor: Intel (0x8086) 04:47:45 INFO - Device ID: 0x0a2e 04:47:45 INFO - Revision ID: 0x0009 04:47:45 INFO - Displays: 04:47:45 INFO - Display: 04:47:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:47:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:47:45 INFO - Display Serial Number: 1600x1200 60 04:47:45 INFO - Main Display: Yes 04:47:45 INFO - Mirror: Off 04:47:45 INFO - Online: Yes 04:47:45 INFO - Rotation: Supported 04:47:45 INFO - Return code: 0 04:47:45 INFO - Running post-action listener: _resource_record_post_action 04:47:45 INFO - Running post-run listener: _resource_record_post_run 04:47:46 INFO - Total resource usage - Wall time: 1193s; CPU: 52.0%; Read bytes: 32161280; Write bytes: 509472768; Read time: 322; Write time: 1686 04:47:46 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 195604992; Write bytes: 197858816; Read time: 17899; Write time: 399 04:47:46 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 31247872; Write bytes: 300698112; Read time: 309; Write time: 1186 04:47:46 INFO - Running post-run listener: _upload_blobber_files 04:47:46 INFO - Blob upload gear active. 04:47:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:47:46 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 04:47:46 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'] 04:47:46 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 04:47:46 INFO - (blobuploader) - INFO - Open directory for files ... 04:47:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:47:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:47:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:47:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:47:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:47:47 INFO - (blobuploader) - INFO - Done attempting. 04:47:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:47:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:47:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:47:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:47:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:47:48 INFO - (blobuploader) - INFO - Done attempting. 04:47:48 INFO - (blobuploader) - INFO - Iteration through files over. 04:47:48 INFO - Return code: 0 04:47:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:47:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:47:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/706f03efc30d2428af9eb75e2c2ef07b5d52fba50aea3f59c64fb83d5b3c75118a620d3f17f99bfabf631c189ed319cbc40c7112ce45964a4f140c6b69c430f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2ee8f34103a1443306e4b91326999ebb86ee6d3e01b019aeed787a9518ffd7a119f77abd41af82789564fc329ab09c61849f2273067e56f9e003dec9b4d5e33"} 04:47:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:47:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:47:48 INFO - Contents: 04:47:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/706f03efc30d2428af9eb75e2c2ef07b5d52fba50aea3f59c64fb83d5b3c75118a620d3f17f99bfabf631c189ed319cbc40c7112ce45964a4f140c6b69c430f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2ee8f34103a1443306e4b91326999ebb86ee6d3e01b019aeed787a9518ffd7a119f77abd41af82789564fc329ab09c61849f2273067e56f9e003dec9b4d5e33"} 04:47:48 INFO - Running post-run listener: copy_logs_to_upload_dir 04:47:48 INFO - Copying logs to upload dir... 04:47:48 INFO - mkdir: /builds/slave/test/build/upload/logs 04:47:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1295.339777 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-05-21 04:47:48.679739) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-21 04:47:48.683709) ========= 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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/706f03efc30d2428af9eb75e2c2ef07b5d52fba50aea3f59c64fb83d5b3c75118a620d3f17f99bfabf631c189ed319cbc40c7112ce45964a4f140c6b69c430f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2ee8f34103a1443306e4b91326999ebb86ee6d3e01b019aeed787a9518ffd7a119f77abd41af82789564fc329ab09c61849f2273067e56f9e003dec9b4d5e33"} build_url:https://queue.taskcluster.net/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010421 build_url: 'https://queue.taskcluster.net/v1/task/evEzMVYnTAeV9G_tJLeOew/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/706f03efc30d2428af9eb75e2c2ef07b5d52fba50aea3f59c64fb83d5b3c75118a620d3f17f99bfabf631c189ed319cbc40c7112ce45964a4f140c6b69c430f2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2ee8f34103a1443306e4b91326999ebb86ee6d3e01b019aeed787a9518ffd7a119f77abd41af82789564fc329ab09c61849f2273067e56f9e003dec9b4d5e33"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-21 04:47:48.715835) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 04:47:48.716576) ========= 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.Y86ti3m1Zw/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.TCdJXPdEqc/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.005261 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 04:47:48.766019) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-21 04:47:48.766405) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-21 04:47:48.766959) ========= ========= Total master_lag: 0.22 =========