builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0381 starttime: 1463544392.34 results: success (0) buildid: 20160517194042 builduid: 23fc05504c824147b62ddecbe7228729 revision: 8483a86bf6720032befd0866987adcfe6b12f03f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 21:06:32.342268) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 21:06:32.342717) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-05-17 21:06:32.342990) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-05-17 21:06:38.596682) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 21:06:38.597009) ========= 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.akB56jrxiV/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.Vzuo5bmV4D/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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-17 21:06:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 165M=0s 2016-05-17 21:06:39 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.548866 ========= master_lag: 0.54 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 21:06:39.689419) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 21:06:39.689728) ========= 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.akB56jrxiV/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.Vzuo5bmV4D/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.026432 ========= master_lag: 1.52 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 21:06:41.237902) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 21:06:41.238360) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8483a86bf6720032befd0866987adcfe6b12f03f --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 8483a86bf6720032befd0866987adcfe6b12f03f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.akB56jrxiV/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.Vzuo5bmV4D/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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-17 21:06:41,346 truncating revision to first 12 chars 2016-05-17 21:06:41,347 Setting DEBUG logging. 2016-05-17 21:06:41,347 attempt 1/10 2016-05-17 21:06:41,347 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/8483a86bf672?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 21:06:42,306 unpacking tar archive at: mozilla-beta-8483a86bf672/testing/mozharness/ program finished with exit code 0 elapsedTime=1.205982 ========= master_lag: 0.36 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 21:06:42.801137) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 21:06:42.801515) ========= script_repo_revision: 8483a86bf6720032befd0866987adcfe6b12f03f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 21:06:42.801944) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2016-05-17 21:06:42.808345) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 3 secs) (at 2016-05-17 21:06:46.745787) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-17 21:06:46.746234) ========= /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.akB56jrxiV/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.Vzuo5bmV4D/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 21:06:46 INFO - MultiFileLogger online at 20160517 21:06:46 in /builds/slave/test 21:06:46 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 21:06:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:06:46 INFO - {'append_to_log': False, 21:06:46 INFO - 'base_work_dir': '/builds/slave/test', 21:06:46 INFO - 'blob_upload_branch': 'Mozilla-Beta', 21:06:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:06:46 INFO - 'branch': 'Mozilla-Beta', 21:06:46 INFO - 'buildbot_json_path': 'buildprops.json', 21:06:46 INFO - 'config_files': ('talos/mac_config.py',), 21:06:46 INFO - 'default_actions': ('clobber', 21:06:46 INFO - 'read-buildbot-config', 21:06:46 INFO - 'download-and-extract', 21:06:46 INFO - 'populate-webroot', 21:06:46 INFO - 'create-virtualenv', 21:06:46 INFO - 'install', 21:06:46 INFO - 'run-tests'), 21:06:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:06:46 INFO - 'download_minidump_stackwalk': True, 21:06:46 INFO - 'download_symbols': 'ondemand', 21:06:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:06:46 INFO - 'tooltool.py': '/tools/tooltool.py', 21:06:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:06:46 INFO - '/tools/misc-python/virtualenv.py')}, 21:06:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:06:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:06:46 INFO - 'installer_path': 'installer.exe', 21:06:46 INFO - 'log_level': 'info', 21:06:46 INFO - 'log_name': 'talos', 21:06:46 INFO - 'log_to_console': True, 21:06:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:06:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:06:46 INFO - 'opt_config_files': (), 21:06:46 INFO - 'pip_index': False, 21:06:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:06:46 INFO - 'cmd': ('bash', 21:06:46 INFO - '-c', 21:06:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:06:46 INFO - 'enabled': True, 21:06:46 INFO - 'halt_on_failure': False, 21:06:46 INFO - 'name': 'check_screen_resolution'},), 21:06:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:06:46 INFO - 'cmd': ('bash', 21:06:46 INFO - '-c', 21:06:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:06:46 INFO - 'enabled': True, 21:06:46 INFO - 'halt_on_failure': False, 21:06:46 INFO - 'name': 'check_screen_resolution'},), 21:06:46 INFO - 'run_cmd_checks_enabled': True, 21:06:46 INFO - 'sps_profile': False, 21:06:46 INFO - 'sps_profile_interval': 0, 21:06:46 INFO - 'suite': 'dromaeojs', 21:06:46 INFO - 'system_bits': '32', 21:06:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:06:46 INFO - 'title': 't-yosemite-r7-0381', 21:06:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:06:46 INFO - 'use_talos_json': True, 21:06:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:06:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:06:46 INFO - 'work_dir': 'build'} 21:06:46 INFO - ##### 21:06:46 INFO - ##### Running clobber step. 21:06:46 INFO - ##### 21:06:46 INFO - Running pre-action listener: _resource_record_pre_action 21:06:46 INFO - Running main action method: clobber 21:06:46 INFO - rmtree: /builds/slave/test/build 21:06:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:06:49 INFO - Running post-action listener: _resource_record_post_action 21:06:49 INFO - ##### 21:06:49 INFO - ##### Running read-buildbot-config step. 21:06:49 INFO - ##### 21:06:49 INFO - Running pre-action listener: _resource_record_pre_action 21:06:49 INFO - Running main action method: read_buildbot_config 21:06:49 INFO - Using buildbot properties: 21:06:49 INFO - { 21:06:49 INFO - "project": "", 21:06:49 INFO - "product": "firefox", 21:06:49 INFO - "script_repo_revision": "production", 21:06:49 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 21:06:49 INFO - "repository": "", 21:06:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 21:06:49 INFO - "buildid": "20160517194042", 21:06:49 INFO - "pgo_build": "False", 21:06:49 INFO - "basedir": "/builds/slave/test", 21:06:49 INFO - "buildnumber": 14, 21:06:49 INFO - "slavename": "t-yosemite-r7-0381", 21:06:49 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 21:06:49 INFO - "platform": "yosemite_r7", 21:06:49 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 21:06:49 INFO - "branch": "mozilla-beta", 21:06:49 INFO - "repo_path": "releases/mozilla-beta", 21:06:49 INFO - "revision": "8483a86bf6720032befd0866987adcfe6b12f03f", 21:06:49 INFO - "stage_platform": "macosx64", 21:06:49 INFO - "builduid": "23fc05504c824147b62ddecbe7228729", 21:06:49 INFO - "slavebuilddir": "test" 21:06:49 INFO - } 21:06:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 21:06:49 INFO - Running post-action listener: _resource_record_post_action 21:06:49 INFO - ##### 21:06:49 INFO - ##### Running download-and-extract step. 21:06:49 INFO - ##### 21:06:49 INFO - Running pre-action listener: _resource_record_pre_action 21:06:49 INFO - Running main action method: download_and_extract 21:06:49 INFO - mkdir: /builds/slave/test/build/tests 21:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:06:49 INFO - https://queue.taskcluster.net/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:06:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 21:06:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 21:06:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/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 21:06:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/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 21:06:50 INFO - Downloaded 1241 bytes. 21:06:50 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 21:06:50 INFO - Using the following test package requirements: 21:06:50 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 21:06:50 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 21:06:50 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 21:06:50 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 21:06:50 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 21:06:50 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 21:06:50 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 21:06:50 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 21:06:50 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 21:06:50 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 21:06:50 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 21:06:50 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 21:06:50 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 21:06:50 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 21:06:50 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 21:06:50 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 21:06:50 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 21:06:50 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 21:06:50 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 21:06:50 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')]} 21:06:50 INFO - https://queue.taskcluster.net/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:06:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 21:06:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 21:06:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/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 21:06:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/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 21:06:52 INFO - Downloaded 17747728 bytes. 21:06:52 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'] 21:06:52 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 21:06:55 INFO - Return code: 0 21:06:55 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 21:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:06:55 INFO - https://queue.taskcluster.net/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:06:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 21:06:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 21:06:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/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 21:06:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/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 21:06:58 INFO - Downloaded 17747728 bytes. 21:06:58 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'] 21:06:58 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 21:07:02 INFO - Return code: 0 21:07:02 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')]} 21:07:02 INFO - https://queue.taskcluster.net/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:07:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 21:07:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 21:07:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/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 21:07:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/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 21:07:04 INFO - Downloaded 10845194 bytes. 21:07:04 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'] 21:07:04 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 21:07:05 INFO - Return code: 0 21:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:07:05 INFO - https://queue.taskcluster.net/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 21:07:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 21:07:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 21:07:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 21:07:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flwtilp2TD6jk5yeMErzJw/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 21:07:09 INFO - Downloaded 88288209 bytes. 21:07:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 21:07:09 INFO - mkdir: /builds/slave/test/properties 21:07:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:07:09 INFO - Writing to file /builds/slave/test/properties/build_url 21:07:09 INFO - Contents: 21:07:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 21:07:11 INFO - Running post-action listener: _resource_record_post_action 21:07:11 INFO - Running post-action listener: set_extra_try_arguments 21:07:11 INFO - ##### 21:07:11 INFO - ##### Running populate-webroot step. 21:07:11 INFO - ##### 21:07:11 INFO - Running pre-action listener: _resource_record_pre_action 21:07:11 INFO - Running main action method: populate_webroot 21:07:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:07:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:07:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:07:11 INFO - '--tppagecycles', 21:07:11 INFO - '7'], 21:07:11 INFO - 'tests': ['tsvgm']}}, 21:07:11 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:07:11 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:07:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:07:11 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 21:07:11 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:07:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:07:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:07:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:07:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:07:11 INFO - 'talos_options': ['--e10s'], 21:07:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:07:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:07:11 INFO - 'tests': ['damp', 'tps']}, 21:07:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:07:11 INFO - 'talos_options': ['--e10s'], 21:07:11 INFO - 'tests': ['damp', 'tps']}, 21:07:11 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:07:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:07:11 INFO - 'other': {'tests': ['a11yr', 21:07:11 INFO - 'ts_paint', 21:07:11 INFO - 'tpaint', 21:07:11 INFO - 'sessionrestore', 21:07:11 INFO - 'sessionrestore_no_auto_restore', 21:07:11 INFO - 'tabpaint']}, 21:07:11 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:07:11 INFO - 'tests': ['a11yr', 21:07:11 INFO - 'ts_paint', 21:07:11 INFO - 'tpaint', 21:07:11 INFO - 'sessionrestore', 21:07:11 INFO - 'sessionrestore_no_auto_restore', 21:07:11 INFO - 'tabpaint']}, 21:07:11 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:07:11 INFO - 'tests': ['a11yr', 21:07:11 INFO - 'ts_paint', 21:07:11 INFO - 'tpaint', 21:07:11 INFO - 'sessionrestore', 21:07:11 INFO - 'sessionrestore_no_auto_restore', 21:07:11 INFO - 'tabpaint']}, 21:07:11 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:07:11 INFO - 'tests': ['a11yr', 21:07:11 INFO - 'ts_paint', 21:07:11 INFO - 'tpaint', 21:07:11 INFO - 'sessionrestore', 21:07:11 INFO - 'sessionrestore_no_auto_restore', 21:07:11 INFO - 'tabpaint']}, 21:07:11 INFO - 'other_l64': {'tests': ['a11yr', 21:07:11 INFO - 'ts_paint', 21:07:11 INFO - 'tpaint', 21:07:11 INFO - 'sessionrestore', 21:07:11 INFO - 'sessionrestore_no_auto_restore', 21:07:11 INFO - 'tabpaint']}, 21:07:11 INFO - 'other_nol64': {'tests': ['a11yr', 21:07:11 INFO - 'ts_paint', 21:07:11 INFO - 'tpaint', 21:07:11 INFO - 'sessionrestore', 21:07:11 INFO - 'sessionrestore_no_auto_restore', 21:07:11 INFO - 'tabpaint']}, 21:07:11 INFO - 'svgr': {'tests': ['tsvgx', 21:07:11 INFO - 'tsvgr_opacity', 21:07:11 INFO - 'tart', 21:07:11 INFO - 'tscrollx', 21:07:11 INFO - 'cart']}, 21:07:11 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:07:11 INFO - 'tests': ['tsvgx', 21:07:11 INFO - 'tsvgr_opacity', 21:07:11 INFO - 'tart', 21:07:11 INFO - 'tscrollx', 21:07:11 INFO - 'cart']}, 21:07:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:07:11 INFO - 'tests': ['tp5o']}, 21:07:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:07:11 INFO - 'talos_options': ['--e10s'], 21:07:11 INFO - 'tests': ['tp5o']}, 21:07:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:07:11 INFO - 'talos_options': ['--xperf_path', 21:07:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:07:11 INFO - 'tests': ['tp5n']}, 21:07:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:07:11 INFO - 'talos_options': ['--e10s', 21:07:11 INFO - '--xperf_path', 21:07:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:07:11 INFO - 'tests': ['tp5n']}}, 21:07:11 INFO - 'talos.zip': {'path': '', 21:07:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:07:11 INFO - Running post-action listener: _resource_record_post_action 21:07:11 INFO - ##### 21:07:11 INFO - ##### Running create-virtualenv step. 21:07:11 INFO - ##### 21:07:11 INFO - Running pre-action listener: _resource_record_pre_action 21:07:11 INFO - Running main action method: create_virtualenv 21:07:11 INFO - Creating virtualenv /builds/slave/test/build/venv 21:07:11 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 21:07:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:07:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:07:11 INFO - Using real prefix '/tools/python27' 21:07:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:07:11 INFO - Installing distribute.............................................................................................................................................................................................done. 21:07:14 INFO - Installing pip.................done. 21:07:14 INFO - Return code: 0 21:07:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:07: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')]} 21:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07: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')]} 21:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:14 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 0x1102e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9cbd74330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11018dd30>, '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 0x10fff1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110184b20>, '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.akB56jrxiV/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.Vzuo5bmV4D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 21:07:14 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 21:07:14 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 21:07:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.akB56jrxiV/Render', 21:07:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:14 INFO - 'HOME': '/Users/cltbld', 21:07:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:14 INFO - 'LOGNAME': 'cltbld', 21:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:14 INFO - 'MOZ_NO_REMOTE': '1', 21:07:14 INFO - 'NO_EM_RESTART': '1', 21:07:14 INFO - 'PAGER': '/bin/cat', 21:07:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:14 INFO - 'PWD': '/builds/slave/test', 21:07:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:14 INFO - 'SHELL': '/bin/bash', 21:07:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vzuo5bmV4D/Listeners', 21:07:14 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:07:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:14 INFO - 'USER': 'cltbld', 21:07:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:14 INFO - 'XPC_FLAGS': '0x0', 21:07:14 INFO - 'XPC_SERVICE_NAME': '0', 21:07:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:15 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 21:07:15 INFO - Cleaning up... 21:07:15 INFO - Return code: 0 21:07:15 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:07: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')]} 21:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07: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')]} 21:07:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:15 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 0x1102e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9cbd74330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11018dd30>, '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 0x10fff1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110184b20>, '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.akB56jrxiV/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.Vzuo5bmV4D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 21:07:15 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 21:07:15 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 21:07:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.akB56jrxiV/Render', 21:07:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:15 INFO - 'HOME': '/Users/cltbld', 21:07:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:15 INFO - 'LOGNAME': 'cltbld', 21:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:15 INFO - 'MOZ_NO_REMOTE': '1', 21:07:15 INFO - 'NO_EM_RESTART': '1', 21:07:15 INFO - 'PAGER': '/bin/cat', 21:07:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:15 INFO - 'PWD': '/builds/slave/test', 21:07:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:15 INFO - 'SHELL': '/bin/bash', 21:07:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vzuo5bmV4D/Listeners', 21:07:15 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:07:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:15 INFO - 'USER': 'cltbld', 21:07:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:15 INFO - 'XPC_FLAGS': '0x0', 21:07:15 INFO - 'XPC_SERVICE_NAME': '0', 21:07:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:15 INFO - Downloading/unpacking psutil>=0.7.1 21:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:07:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:07:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:07:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:07:18 INFO - Installing collected packages: psutil 21:07:18 INFO - Running setup.py install for psutil 21:07:18 INFO - building 'psutil._psutil_osx' extension 21:07:18 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 21:07:18 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 21:07:18 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 21:07:18 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 21:07:19 INFO - building 'psutil._psutil_posix' extension 21:07:19 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 21:07:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:07:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:07:19 INFO - ^ 21:07:19 INFO - 1 warning generated. 21:07:19 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 21:07:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:07:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:07:19 INFO - Successfully installed psutil 21:07:19 INFO - Cleaning up... 21:07:19 INFO - Return code: 0 21:07:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:07:19 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')]} 21:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:19 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')]} 21:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:19 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 0x1102e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9cbd74330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11018dd30>, '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 0x10fff1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110184b20>, '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.akB56jrxiV/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.Vzuo5bmV4D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 21:07:19 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 21:07:19 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 21:07:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.akB56jrxiV/Render', 21:07:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:19 INFO - 'HOME': '/Users/cltbld', 21:07:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:19 INFO - 'LOGNAME': 'cltbld', 21:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:19 INFO - 'MOZ_NO_REMOTE': '1', 21:07:19 INFO - 'NO_EM_RESTART': '1', 21:07:19 INFO - 'PAGER': '/bin/cat', 21:07:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:19 INFO - 'PWD': '/builds/slave/test', 21:07:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:19 INFO - 'SHELL': '/bin/bash', 21:07:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vzuo5bmV4D/Listeners', 21:07:19 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:07:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:19 INFO - 'USER': 'cltbld', 21:07:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:19 INFO - 'XPC_FLAGS': '0x0', 21:07:19 INFO - 'XPC_SERVICE_NAME': '0', 21:07:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:19 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:07:19 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 21:07:19 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 21:07:19 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 21:07:19 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 21:07:19 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 21:07:19 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 21:07:21 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:07:22 INFO - Installing collected packages: mozsystemmonitor 21:07:22 INFO - Running setup.py install for mozsystemmonitor 21:07:22 INFO - Successfully installed mozsystemmonitor 21:07:22 INFO - Cleaning up... 21:07:22 INFO - Return code: 0 21:07:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:07:22 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')]} 21:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:22 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')]} 21:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:22 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 0x1102e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9cbd74330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11018dd30>, '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 0x10fff1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110184b20>, '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.akB56jrxiV/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.Vzuo5bmV4D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 21:07:22 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 21:07:22 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 21:07:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.akB56jrxiV/Render', 21:07:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:22 INFO - 'HOME': '/Users/cltbld', 21:07:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:22 INFO - 'LOGNAME': 'cltbld', 21:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:22 INFO - 'MOZ_NO_REMOTE': '1', 21:07:22 INFO - 'NO_EM_RESTART': '1', 21:07:22 INFO - 'PAGER': '/bin/cat', 21:07:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:22 INFO - 'PWD': '/builds/slave/test', 21:07:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:22 INFO - 'SHELL': '/bin/bash', 21:07:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vzuo5bmV4D/Listeners', 21:07:22 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:07:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:22 INFO - 'USER': 'cltbld', 21:07:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:22 INFO - 'XPC_FLAGS': '0x0', 21:07:22 INFO - 'XPC_SERVICE_NAME': '0', 21:07:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:22 INFO - Downloading/unpacking blobuploader==1.2.4 21:07:22 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 21:07:22 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 21:07:22 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 21:07:22 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 21:07:22 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 21:07:22 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 21:07:25 INFO - Downloading blobuploader-1.2.4.tar.gz 21:07:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:07:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:07:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:07:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:07:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:07:26 INFO - Downloading docopt-0.6.1.tar.gz 21:07:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:07:26 INFO - Installing collected packages: blobuploader, requests, docopt 21:07:26 INFO - Running setup.py install for blobuploader 21:07:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:07:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:07:26 INFO - Running setup.py install for requests 21:07:26 INFO - Running setup.py install for docopt 21:07:27 INFO - Successfully installed blobuploader requests docopt 21:07:27 INFO - Cleaning up... 21:07:27 INFO - Return code: 0 21:07:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:27 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 0x1102e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9cbd74330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11018dd30>, '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 0x10fff1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110184b20>, '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.akB56jrxiV/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.Vzuo5bmV4D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 21:07:27 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 21:07:27 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 21:07:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.akB56jrxiV/Render', 21:07:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:27 INFO - 'HOME': '/Users/cltbld', 21:07:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:27 INFO - 'LOGNAME': 'cltbld', 21:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:27 INFO - 'MOZ_NO_REMOTE': '1', 21:07:27 INFO - 'NO_EM_RESTART': '1', 21:07:27 INFO - 'PAGER': '/bin/cat', 21:07:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:27 INFO - 'PWD': '/builds/slave/test', 21:07:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:27 INFO - 'SHELL': '/bin/bash', 21:07:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vzuo5bmV4D/Listeners', 21:07:27 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:07:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:27 INFO - 'USER': 'cltbld', 21:07:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:27 INFO - 'XPC_FLAGS': '0x0', 21:07:27 INFO - 'XPC_SERVICE_NAME': '0', 21:07:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:07:27 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-JllAjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:07:27 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-2wEGF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:07:27 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-LEER03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:07:27 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-MQgHo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:07:27 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-9lD2LU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:07:27 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-odXZcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:07:27 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Je6go5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:07:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-HeQnNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:07:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-jkSXvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:07:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ZudpyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:07:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-26dEu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:07:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-4AcCpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:07:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-WPokWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:07:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-x8WbDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:07:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ORDXr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:07:28 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-agwdFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:07:29 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-VqbjMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:07:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:07:29 INFO - Running setup.py install for manifestparser 21:07:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:07:29 INFO - Running setup.py install for mozcrash 21:07:29 INFO - Running setup.py install for mozdebug 21:07:29 INFO - Running setup.py install for mozdevice 21:07:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:07:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:07:29 INFO - Running setup.py install for mozfile 21:07:29 INFO - Running setup.py install for mozhttpd 21:07:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:07:29 INFO - Running setup.py install for mozinfo 21:07:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:07:30 INFO - Running setup.py install for mozInstall 21:07:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:07:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:07:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:07:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:07:30 INFO - Running setup.py install for mozleak 21:07:30 INFO - Running setup.py install for mozlog 21:07:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:07:30 INFO - Running setup.py install for moznetwork 21:07:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:07:30 INFO - Running setup.py install for mozprocess 21:07:30 INFO - Running setup.py install for mozprofile 21:07:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:07:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:07:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:07:31 INFO - Running setup.py install for mozrunner 21:07:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:07:31 INFO - Running setup.py install for mozscreenshot 21:07:31 INFO - Running setup.py install for moztest 21:07:31 INFO - Running setup.py install for mozversion 21:07:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:07:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:07:31 INFO - Cleaning up... 21:07:31 INFO - Return code: 0 21:07:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:07:31 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')]} 21:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:31 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')]} 21:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:31 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 0x1102e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9cbd74330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11018dd30>, '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 0x10fff1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110184b20>, '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.akB56jrxiV/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.Vzuo5bmV4D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 21:07:31 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 21:07:31 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 21:07:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.akB56jrxiV/Render', 21:07:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:31 INFO - 'HOME': '/Users/cltbld', 21:07:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:31 INFO - 'LOGNAME': 'cltbld', 21:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:31 INFO - 'MOZ_NO_REMOTE': '1', 21:07:31 INFO - 'NO_EM_RESTART': '1', 21:07:31 INFO - 'PAGER': '/bin/cat', 21:07:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:31 INFO - 'PWD': '/builds/slave/test', 21:07:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:31 INFO - 'SHELL': '/bin/bash', 21:07:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vzuo5bmV4D/Listeners', 21:07:31 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:07:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:31 INFO - 'USER': 'cltbld', 21:07:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:31 INFO - 'XPC_FLAGS': '0x0', 21:07:31 INFO - 'XPC_SERVICE_NAME': '0', 21:07:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:07:31 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-ODercm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:07:32 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)) 21:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:07:32 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-JCA99W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:07:32 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)) 21:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:07:32 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-d38y7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:07:32 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)) 21:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:07:32 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-WvS0Kl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:07:32 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)) 21:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:07:32 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-9GM0w_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:07:32 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)) 21:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:07:32 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-PmlAIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:07:32 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)) 21:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:07:32 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-UsdWTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:07:32 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)) 21:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:07:32 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-AHT4hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:07:32 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)) 21:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:07:32 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-PM9haE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:07:32 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)) 21:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:07:32 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-AYyaQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:07:32 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)) 21:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:07:32 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-zQ20wX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:07:33 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)) 21:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:07:33 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-x9cJzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:07:33 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)) 21:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:07:33 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Vq4nEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:07:33 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)) 21:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:07:33 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-DJBaV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:07:33 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)) 21:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:07:33 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-WXu0bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:07:33 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)) 21:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:07:33 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-Gg05yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:07:33 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)) 21:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:07:33 INFO - Running setup.py (path:/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/pip-TtHB5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:07:33 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)) 21:07:33 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)) 21:07:33 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)) 21:07:33 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)) 21:07:33 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)) 21:07:33 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)) 21:07:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:07:33 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 21:07:33 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 21:07:33 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 21:07:33 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 21:07:33 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 21:07:33 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 21:07:36 INFO - Downloading blessings-1.6.tar.gz 21:07:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:07:36 INFO - Installing collected packages: blessings 21:07:36 INFO - Running setup.py install for blessings 21:07:36 INFO - Successfully installed blessings 21:07:36 INFO - Cleaning up... 21:07:36 INFO - Return code: 0 21:07:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:07:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:07:36 INFO - Reading from file tmpfile_stdout 21:07:36 INFO - Current package versions: 21:07:36 INFO - blessings == 1.6 21:07:36 INFO - blobuploader == 1.2.4 21:07:36 INFO - docopt == 0.6.1 21:07:36 INFO - manifestparser == 1.1 21:07:36 INFO - mozInstall == 1.12 21:07:36 INFO - mozcrash == 0.17 21:07:36 INFO - mozdebug == 0.1 21:07:36 INFO - mozdevice == 0.48 21:07:36 INFO - mozfile == 1.2 21:07:36 INFO - mozhttpd == 0.7 21:07:36 INFO - mozinfo == 0.9 21:07:36 INFO - mozleak == 0.1 21:07:36 INFO - mozlog == 3.1 21:07:36 INFO - moznetwork == 0.27 21:07:36 INFO - mozprocess == 0.22 21:07:36 INFO - mozprofile == 0.28 21:07:36 INFO - mozrunner == 6.11 21:07:36 INFO - mozscreenshot == 0.1 21:07:36 INFO - mozsystemmonitor == 0.0 21:07:36 INFO - moztest == 0.7 21:07:36 INFO - mozversion == 1.4 21:07:36 INFO - psutil == 3.1.1 21:07:36 INFO - requests == 1.2.3 21:07:36 INFO - wsgiref == 0.1.2 21:07:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:07:36 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')]} 21:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:36 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')]} 21:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:36 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 0x1102e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9cbd74330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11018dd30>, '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 0x10fff1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110184b20>, '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.akB56jrxiV/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.Vzuo5bmV4D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 21:07:36 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 21:07:36 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 21:07:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.akB56jrxiV/Render', 21:07:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:36 INFO - 'HOME': '/Users/cltbld', 21:07:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:36 INFO - 'LOGNAME': 'cltbld', 21:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:36 INFO - 'MOZ_NO_REMOTE': '1', 21:07:36 INFO - 'NO_EM_RESTART': '1', 21:07:36 INFO - 'PAGER': '/bin/cat', 21:07:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:36 INFO - 'PWD': '/builds/slave/test', 21:07:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:36 INFO - 'SHELL': '/bin/bash', 21:07:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vzuo5bmV4D/Listeners', 21:07:36 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:07:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:36 INFO - 'USER': 'cltbld', 21:07:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:36 INFO - 'XPC_FLAGS': '0x0', 21:07:36 INFO - 'XPC_SERVICE_NAME': '0', 21:07:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:37 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)) 21:07:37 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)) 21:07:37 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)) 21:07:37 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)) 21:07:37 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)) 21:07:37 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)) 21:07:37 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)) 21:07:37 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)) 21:07:37 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)) 21:07:37 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)) 21:07:37 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)) 21:07:37 INFO - Cleaning up... 21:07:37 INFO - Return code: 0 21:07:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:07:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:07:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:37 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 0x1102e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9cbd74330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11018dd30>, '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 0x10fff1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110184b20>, '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.akB56jrxiV/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.Vzuo5bmV4D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 21:07:37 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 21:07:37 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 21:07:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.akB56jrxiV/Render', 21:07:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:37 INFO - 'HOME': '/Users/cltbld', 21:07:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:37 INFO - 'LOGNAME': 'cltbld', 21:07:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:37 INFO - 'MOZ_NO_REMOTE': '1', 21:07:37 INFO - 'NO_EM_RESTART': '1', 21:07:37 INFO - 'PAGER': '/bin/cat', 21:07:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:37 INFO - 'PWD': '/builds/slave/test', 21:07:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:37 INFO - 'SHELL': '/bin/bash', 21:07:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vzuo5bmV4D/Listeners', 21:07:37 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:07:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:37 INFO - 'USER': 'cltbld', 21:07:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:37 INFO - 'XPC_FLAGS': '0x0', 21:07:37 INFO - 'XPC_SERVICE_NAME': '0', 21:07:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:37 INFO - Downloading/unpacking jsonschema 21:07:37 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 21:07:37 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 21:07:37 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 21:07:37 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 21:07:37 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 21:07:37 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 21:07:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:07:39 INFO - Downloading/unpacking functools32 (from jsonschema) 21:07:39 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 21:07:39 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 21:07:39 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 21:07:39 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 21:07:39 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 21:07:39 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 21:07:40 INFO - Downloading functools32-3.2.3-2.tar.gz 21:07:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:07:40 INFO - warning: no files found matching '*.txt' 21:07:40 INFO - no previously-included directories found matching 'build' 21:07:40 INFO - no previously-included directories found matching 'dist' 21:07:40 INFO - no previously-included directories found matching '.git*' 21:07:40 INFO - Installing collected packages: jsonschema, functools32 21:07:40 INFO - Running setup.py install for functools32 21:07:40 INFO - warning: no files found matching '*.txt' 21:07:40 INFO - no previously-included directories found matching 'build' 21:07:40 INFO - no previously-included directories found matching 'dist' 21:07:40 INFO - no previously-included directories found matching '.git*' 21:07:40 INFO - Successfully installed jsonschema functools32 21:07:40 INFO - Cleaning up... 21:07:40 INFO - Return code: 0 21:07:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:07:40 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')]} 21:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:40 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')]} 21:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:07:40 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 0x1102e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f04c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9cbd74330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11018dd30>, '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 0x10fff1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110184b20>, '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.akB56jrxiV/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.Vzuo5bmV4D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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 21:07:40 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 21:07:40 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 21:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.akB56jrxiV/Render', 21:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:07:40 INFO - 'HOME': '/Users/cltbld', 21:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:07:40 INFO - 'LOGNAME': 'cltbld', 21:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:07:40 INFO - 'MOZ_NO_REMOTE': '1', 21:07:40 INFO - 'NO_EM_RESTART': '1', 21:07:40 INFO - 'PAGER': '/bin/cat', 21:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:07:40 INFO - 'PWD': '/builds/slave/test', 21:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:07:40 INFO - 'SHELL': '/bin/bash', 21:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vzuo5bmV4D/Listeners', 21:07:40 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:07:40 INFO - 'USER': 'cltbld', 21:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:07:40 INFO - 'XPC_FLAGS': '0x0', 21:07:40 INFO - 'XPC_SERVICE_NAME': '0', 21:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:07:40 INFO - Downloading/unpacking flake8 21:07: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 21:07: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 21:07: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 21:07: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 21:07: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 21:07: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 21:07:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:07:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:07: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 21:07: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 21:07: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 21:07: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 21:07: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 21:07: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 21:07:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:07:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:07: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 21:07: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 21:07: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 21:07: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 21:07: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 21:07: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 21:07:43 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:07: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 21:07: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 21:07: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 21:07: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 21:07: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 21:07: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 21:07:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:07:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:07:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:07:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:07:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:07:44 INFO - no previously-included directories found matching 'docs/_build' 21:07:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:07:44 INFO - Running setup.py install for pep8 21:07:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:07:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:07:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:07:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:07:44 INFO - no previously-included directories found matching 'docs/_build' 21:07:44 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:07:44 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:07:44 INFO - Cleaning up... 21:07:44 INFO - Return code: 0 21:07:44 INFO - Running post-action listener: _resource_record_post_action 21:07:44 INFO - Running post-action listener: _start_resource_monitoring 21:07:44 INFO - Starting resource monitoring. 21:07:44 INFO - ##### 21:07:44 INFO - ##### Running install step. 21:07:44 INFO - ##### 21:07:44 INFO - Running pre-action listener: _resource_record_pre_action 21:07:44 INFO - Running main action method: install 21:07:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:07:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:07:45 INFO - Reading from file tmpfile_stdout 21:07:45 INFO - Detecting whether we're running mozinstall >=1.0... 21:07:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:07:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:07:45 INFO - Reading from file tmpfile_stdout 21:07:45 INFO - Output received: 21:07:45 INFO - Usage: mozinstall [options] installer 21:07:45 INFO - Options: 21:07:45 INFO - -h, --help show this help message and exit 21:07:45 INFO - -d DEST, --destination=DEST 21:07:45 INFO - Directory to install application into. [default: 21:07:45 INFO - "/builds/slave/test"] 21:07:45 INFO - --app=APP Application being installed. [default: firefox] 21:07:45 INFO - mkdir: /builds/slave/test/build/application 21:07:45 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'] 21:07:45 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 21:08:07 INFO - Reading from file tmpfile_stdout 21:08:07 INFO - Output received: 21:08:07 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 21:08:07 INFO - Running post-action listener: _resource_record_post_action 21:08:07 INFO - ##### 21:08:07 INFO - ##### Running run-tests step. 21:08:07 INFO - ##### 21:08:07 INFO - Running pre-action listener: _resource_record_pre_action 21:08:07 INFO - Running main action method: run_tests 21:08:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:08:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:08:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:08:07 INFO - 2016-05-17 21:08:07.596 screenresolution[1646:8163] starting screenresolution argv=screenresolution get 21:08:07 INFO - 2016-05-17 21:08:07.599 screenresolution[1646:8163] Display 0: 1600x1200x32@60 21:08:07 INFO - 2016-05-17 21:08:07.604 screenresolution[1647:8165] starting screenresolution argv=screenresolution list 21:08:07 INFO - Available Modes on Display 0 21:08:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:08:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:08:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:08:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:08:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:08:07 INFO - Intel Iris: 21:08:07 INFO - Chipset Model: Intel Iris 21:08:07 INFO - Type: GPU 21:08:07 INFO - Bus: Built-In 21:08:07 INFO - VRAM (Dynamic, Max): 1536 MB 21:08:07 INFO - Vendor: Intel (0x8086) 21:08:07 INFO - Device ID: 0x0a2e 21:08:07 INFO - Revision ID: 0x0009 21:08:07 INFO - Displays: 21:08:07 INFO - Display: 21:08:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:08:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:08:07 INFO - Display Serial Number: 1600x1200 60 21:08:07 INFO - Main Display: Yes 21:08:07 INFO - Mirror: Off 21:08:07 INFO - Online: Yes 21:08:07 INFO - Rotation: Supported 21:08:07 INFO - Return code: 0 21:08:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:08:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:08:07 INFO - Python 2.7.3 21:08:07 INFO - Return code: 0 21:08:07 INFO - grabbing minidump binary from tooltool 21:08:07 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')]} 21:08:07 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 0x11018dd30>, '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 0x10fff1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110184b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:08:07 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 21:08:07 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 21:08:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:08:07 INFO - Return code: 0 21:08:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:08:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:08:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:08:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:08:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:08:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:08:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:08:09 INFO - Return code: 0 21:08:09 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/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0381', '--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 21:08:09 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/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0381 --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 21:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.akB56jrxiV/Render', 21:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:08:09 INFO - 'HOME': '/Users/cltbld', 21:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:08:09 INFO - 'LOGNAME': 'cltbld', 21:08:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:08:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:09 INFO - 'MOZ_NO_REMOTE': '1', 21:08:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:08:09 INFO - 'NO_EM_RESTART': '1', 21:08:09 INFO - 'PAGER': '/bin/cat', 21:08:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:09 INFO - 'PWD': '/builds/slave/test', 21:08:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:08:09 INFO - 'SHELL': '/bin/bash', 21:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vzuo5bmV4D/Listeners', 21:08:09 INFO - 'TMPDIR': '/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/', 21:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:08:09 INFO - 'USER': 'cltbld', 21:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:09 INFO - 'XPC_FLAGS': '0x0', 21:08:09 INFO - 'XPC_SERVICE_NAME': '0', 21:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:08:09 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/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0381', '--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 21:08:09 INFO - mozversion application_buildid: 20160517194042 21:08:09 INFO - mozversion application_changeset: 8483a86bf6720032befd0866987adcfe6b12f03f 21:08:09 INFO - mozversion application_display_name: Firefox 21:08:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:08:09 INFO - mozversion application_name: Firefox 21:08:09 INFO - mozversion application_remotingname: firefox 21:08:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:08:09 INFO - mozversion application_vendor: Mozilla 21:08:09 INFO - mozversion application_version: 47.0 21:08:09 INFO - mozversion platform_buildid: 20160517194042 21:08:09 INFO - mozversion platform_changeset: 8483a86bf6720032befd0866987adcfe6b12f03f 21:08:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:08:09 INFO - mozversion platform_version: 47.0 21:08:09 INFO - using testdate: 1463544489 21:08:09 INFO - actual date: 1463544489 21:08:09 INFO - starting webserver on 'localhost:49272' 21:08:09 INFO - SUITE-START | Running 2 tests 21:08:09 INFO - TEST-START | dromaeo_css 21:08:09 INFO - operating with platform_type : mac_ 21:08:09 INFO - Initialising browser for dromaeo_css test... 21:08:09 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmp3kd_xC/profile http://localhost:49272/getInfo.html) 21:08:11 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 21:08:11 INFO - PROCESS | 1665 | colorDepth:24 21:08:11 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 21:08:11 INFO - PROCESS | 1665 | __metrics 21:08:11 INFO - PROCESS | 1665 | 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" 21:08:11 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:08:12 INFO - TEST-INFO | 1665: exit 0 21:08:12 INFO - Browser initialized. 21:08:12 INFO - Running cycle 1/1 for dromaeo_css test... 21:08:12 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmp3kd_xC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:08:24 INFO - PROCESS | 1669 | RSS: Main: 157196288 21:08:24 INFO - PROCESS | 1669 | 21:09:14 INFO - PROCESS | 1669 | 1463544554972 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] 21:11:24 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 21:11:26 INFO - PROCESS | 1669 | RSS: Main: 279502848 21:11:26 INFO - PROCESS | 1669 | 21:14:28 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 21:14:29 INFO - PROCESS | 1669 | RSS: Main: 288083968 21:14:29 INFO - PROCESS | 1669 | 21:17:31 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 21:17:31 INFO - PROCESS | 1669 | RSS: Main: 297328640 21:17:31 INFO - PROCESS | 1669 | 21:20:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 21:20:34 INFO - PROCESS | 1669 | RSS: Main: 254775296 21:20:34 INFO - PROCESS | 1669 | 21:23:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 21:23:37 INFO - PROCESS | 1669 | RSS: Main: 254976000 21:23:37 INFO - PROCESS | 1669 | 21:26:40 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 21:26:40 INFO - PROCESS | 1669 | RSS: Main: 302391296 21:26:40 INFO - PROCESS | 1669 | 21:26:40 INFO - PROCESS | 1669 | __start_tp_report 21:26:40 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 21:26:40 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 21:26:40 INFO - PROCESS | 1669 | |i|pagename|runs| 21:26:40 INFO - PROCESS | 1669 | |0;dojo.html;3535.4873543336207;3662.523871896653;3664.633536646336;3668.1196512836927;3723.6276372362768;5953.464188223059;5965.642061476315;5965.821025369235;5978.372270911557;5980.79067232647;5946.762129514825;5949.196858424111;5987.461128498434;5995.520358371331;5999.190109335238;5166.535011848933;5316.415194328631;5339.145736682131;5355.973220133894;5386.78452861885;5377.865553361177;5397.136458166694;5404.351477822667;5413.079776437995;5415.512603865652;5317.149256119022;5366.865828354284;5379.731013449312;5380.677159370451;5398.379186393556;5385.380681221671;5390.137577987503;5419.647722897999;5420.295361603005;5478.178273259043;5144.254083157921;5334.91997620036;5335.759890804884;5337.6797392156595;5344.812931547377;5701.4013528579735;5768.769249230026;5772.278465191851;5781.566382521286;5799.246098007275;6231.252249730016;6285.088662143963;6287.182666253358;6291.276503202099;6299.716512756937;5279.63042587019;6215.68921553925;6238.750449981995;6255;6280.9685951569945;6020.307664618581;6044.214252147192;6045.546584006217;6058.091286307089;6063.818085457399;6334.619922804554;6346;6399.872002559922;6413.711382987777;6418.518611104186;6296.370362963667;6313;6315.747370105236;6317.842053948688;6333.968330158412;6292.874142517124;6294.276158241769;6312.968435157886;6313.210848643919;6319.210098737658;6190.969045154746;6219.782307619212;6230.501559875199;6297.527685423612;6309.369063093745;6255.624662520172;6276.623402595859;6286.779962701284;6335.049742538656;6338.904916426257;4970.801167953241;4971.254311853251;4980.626453016038;4998.500149984972;5001.724905130252;4413.933790993137;4466.129104824593;4525.954740452619;4533.795979180944;4544.7045942013665;6087.391260873877;6087.756489740361;6104.969475152596;6108.908366374508;6111.327753947062;6293.685315734195;6312.905306420407;6328.398802113792;6334.588251763621;6343.746250149942;5843.824685259449;5844.8538786529825;5939.4357536034;5954.851128721816;6097.969510152421;5470.671759694431;5488.753006114654;5501.367342755554;5506.752196151183;5512.862178445571;6446.548741588138;6477.190351206099;6485.935140648533;6498;6516.967415162894;6194.81415557534;6243.313235543996;6300.149479820166;6344.746210151542;6351.110844481868;6491.805245842632;6514.5765525242605;6517.837054073686;6525.3800888915475;6527.05357723137;6216.285127210429;6260.968695156677;6304.211973503312;6305;6307.24313082433;6213.65824879636;6273.843153921189;6279.623222606659;6296.433321001132;6312.400321969406;6279.277883043325;6285.182926219745;6292.119103325446;6307.211598550181;6315.810525684236;6141.7543298267565;6197.597156184834;6266.968665156645;6298.464630506275;6309.621422714652;6139.815805525842;6192.81421557354;6203.627782332894;6208.565400421927;6220.564560480723;6209.782657606962;6278.6860656966055;6279.120923070683;6281.59169653971;6282.1519094921605;6098.359672234352;6159.81520554384;6182.6599537025895;6188.133661287513;6214.937850621436 21:26:40 INFO - PROCESS | 1669 | |1;ext.html;495.56637941800926;611.3489134072213;617.7621615677964;627.0844566932278;657.7829316325611;26979.5953060704;40103.59896401035;40117.79941100294;40299.798501007455;40361.3945790813;43200;43800;43862;43969;43984.12031759363;9883.308168428195;10117.342372745785;10120.08919197272;10121.443320617363;10129.392236465836;9921.057499537532;10048.598056077748;10073.949630251838;10075.596976120947;10204.234682398812;10161.441120738356;10232.2325825563;10238.53926573306;10253.84619230712;10256;10127.797444051115;10170.93205213453;10199.082082612525;10216.74458138545;10219.897801021969;10050.949745251228;10131.138853197486;10175.898241017569;10202.030807073315;10211.795764084676;42780.930476738016;43140.56859431428;43198.70403887888;43276.78361608203;43277.35083973743;3255;3391.2369716813764;3444.621091679913;3490.7730997485082;3830.195658911604;27711;28535.429291414264;28773.280667983257;29093.41813163746;29156.708432915613;2785.8049936504453;2787.0941943868324;2808.705085965985;2824.477471667748;2832.2494538947194;534251;538496.3075184638;539756;539843;541111;69257;69382;69535;69580.95628565576;69639;79483.20516794873;80278;80307;80314.59842700748;80454.59772701099;18931.716024259647;18938.715919261223;19064.42806715801;19158.521036973907;19184.232630694616;14615.196164210858;14698.853011469962;14741.92629036848;14748.778768318483;14749.188794616184;22666;22690.886545567166;22790.08839646429;23030.42423939415;23093;7559.244075592506;8143.389245806588;8726.520041397784;9322.30082743797;9341.532923353805;998.4025559105373;1053.070794174331;1086.9673909782719;1099.301943266032;1122.7529943412437;30523;30634;31047.99167699599;31484.842575786974;31491.527627085612;1149.0692539043403;1166.935818529972;1170.5519027694727;1206.1617176062728;1213.7208442058377;11895.524179032915;15107.697846043018;15128.230616302188;16513.752293715603;16821.495355139366;562352;563570;564674;569206;570260;62152;62696;62746.68626656837;62781.372186278466;62924;1417.2417756500222;1448.5509492057497;1531.1196062264244;1539.1227000609545;1573.2527049651324;30238.00641647375;31219.531707024868;31466.685333146375;31613.8419307902;31960.200994975312;50292.74268143326;50656;51092.23361649652;51107.744461277456;51162.74418627883;376196;378521;378829;379040;379136;30534.541981869846;32208.35583288368;32281.22453265093;35684.821575891954;36010.819945900104;44350;44389.556104439835;44489;44837.551624483334;44839.55160448484;36432.81783591171;36627.45058824067;36676.81661591675;36811.63188368082;37352.62647373491;35843.13101899235;36421.27157456889;36664.45003324899;37053.888383348545;37233.88298350945;31383;37240.627593723715;37905.62094379021;38077;38571.8428447147;32702.037877727413;32832.83583582067;33153.83423082869;33522.16194595155;33660.158496037795;25614.615780763677;25616.231513054638;25689;25750.613740793528;26076.73923260743 21:26:40 INFO - PROCESS | 1669 | |2;jquery.html;380.756315957787;380.801982968856;384.7191550168377;386.71962826950454;387.47303667012875;20489.38531844045;21455.785442145574;21979.780202197973;22212.222572209997;22227;17925.10374481277;22387.888060559715;22791.886040569814;23091.42271443215;23113.537729245407;5910.822675319737;6020.819375418744;6115.541334399938;6132.417420345082;6133.601315914473;5848.385919478457;5991.1612374267415;6006.549508786837;6036.758529658809;6054.576179667403;5922.526197904179;5950.37521060289;6062.545309101813;6065.120557519157;6081.847953801146;5874.353821079678;5908.822735317947;6031.758729650809;6069.575129740898;6072.05883088122;5189.195674670432;5271.947280527184;5272.31459910213;5281.683099014072;5281.920771188396;27179.1846244611;27420.725792742018;27493.17520474389;27553.448931021267;27753;2303.700518932528;2311.248844125649;2431.1004928176512;2551.1453663022908;2758.8344699318104;12639.747205055848;15206;15525.378984840592;15537.766933496006;16100.584912263137;1809.1677828199045;1830.3868204151624;1843.1521500109966;1900.7433996410446;1963.7348957890645;108113;108434;109067;110811;111360;10832;10944.288621239593;11072.280301780358;11326.660200194008;11395.829062564067;11733.941330293294;14045.719085618435;14076.71846563063;14079.577612671636;14099.224542650252;7873.960630196927;8811.383203175845;9086.363954523253;9109.726708198765;9220.308476864262;9333.439993600407;9399.906000939904;9433.00953399897;9454.90545094554;9498.620055197714;6428.325025872309;6572.967135164294;6577.572457790228;6586.37429444202;6646;1582.8733701303868;1837.7702787151607;1860.0792607659025;1868.2993877296014;1873.7938826729048;1044.8402273476436;1047.177965297238;1101.944902754859;1108.4734750993375;1163.8777928317575;21412.892935535536;22353.776462235495;22531.662025069636;22622.095116195167;22677.659835102488;1051.3008849115406;1078.5146683992077;1090.1660229924114;1130.8247221680654;1179.2099293473393;11868.465919033664;12302.892739653475;12362.01103911685;12527.373631318398;12584.685382865502;678773;679836;698247;698798;702219;105409.94590054001;105485;133531.33234333765;138223;142216;1109.2512554026;1121.624255874275;1141.223967701954;1143.937083460401;1148.6554033789996;19535.316263930694;20019.899900500986;20300.390988270374;20392.082356293704;20491.4877128073;55543.44456555383;56291.71854140703;56314.43685563256;56961;59177.704111479165;77768.83346749691;78213;78887;79987;80400;8157.265846073882;8294;8426.789330266793;8543.10297418787;8677.652893884175;12469.376531173224;13655.453781848615;13807.585772426843;14149.575512734635;14214.502492412716;10419.374837509773;11215.59805024372;11936.641900742992;12054.397280136134;12058.974987126192;11574.421278935852;11600.651980440263;11704;11963.401829908296;12037.157398981988;10497.42264175447;12042.337671428155;12139.453724582592;12223.327716975651;12239.32683702405;8453.36599755033;8603.268722158688;8622.094680058504;8651.264642505457;8702.91297087021;11573.247738896946;12101.092418068503;12267.57063502773;12290.75418491643;12295.815562766387 21:26:40 INFO - PROCESS | 1669 | |3;mootools.html;1525.161161361251;1669.7244954582495;1698.8471037606616;2060.618785524678;2107.1887323380492;8343.874841877368;8962.73111806646;9179.678711245107;9189.770255743593;9190.586423610937;8809.911900881005;9098.454092754439;9101.226395756368;9101.772455688611;9109.635614575425;2547.5796493578537;2566.2557858221094;2568.4734629400928;2570.498752743216;2578.729233430491;2501.137107697841;2563.628273900283;2567.82025258232;2572.4083460804027;2582.122078493312;2546.3379521324496;2569.755873192053;2587.5083734090463;2593.3386986318465;2597.324695579144;2551.170869467421;2567.075852693029;2580.406506503505;2589.8446093234465;2593.1053786443645;2221.8222542196586;2547.961780573293;2575.188815523104;2584.8836802343753;2610.138654244095;9119.772005699844;9188.586513606904;9192.494412807293;9204.631814727403;9214.769630759285;5616.831495055155;5787.565932555033;5846.503047240947;5854.707264636793;5856.238688970407;3555.9111022224392;3814.0464883779055;3870.6709929656013;3878.9806050969846;3879;952.3952290295642;1059.4384975962753;1081.1512962324616;1083.263380900987;1111.0944580167138;12517.12380133391;12522.060845436537;12545.498180072786;12560.874391256062;12583.559575414909;5045.234168041229;5069.670471419346;5167.9224811627855;5180.093483640348;5184.844454666366;10600;10615.150787936947;10638.308509946828;10652.733681658021;10662.466876656135;5795.536357091422;5819.214406055213;5861.531077513874;5867.882642347215;5902.911456328158;4676.392069031072;4831.879203019882;5042.722650254272;5172.069027574998;5211.661242019257;4082.7305583677066;4127.814248358831;4144.544100148981;4158.688098392572;4228.873133805991;1348.366267854107;1357.097530142464;1359.9796003059962;1368.0765483298733;1375.8511642778392;1403.585942147069;1433.9856601433853;1435.648266174794;1449.4926775628446;1457.6720237946377;8509.914900850912;8578.142185781371;8578.227959483676;8589.441686290496;8627.482351058956;534.3507638219581;538.251829956361;548.528265691505;555.3918459287074;557.7824648387133;6403.679816009181;6433.131527243762;6475.611463312216;6479.514036447191;6525.11910892033;9474.573644186028;10869.239153259354;11398.316101033965;11431.828522572168;11436.94281528587;10368.792624147474;10916.50875710579;11118.666440006813;11225.158113141548;11229.66311010671;8454.281386082252;8645.308375330083;8688.087750786202;8702.738917832476;8719;8479.703210387606;8516.318694504302;8524.74425767228;8579.184977427134;8600.741977740678;9981.500924953869;9999.700008999742;10036.598536058767;10127.949360253151;10248.333858299187;10212.978702130025;10459.47702614851;10618.415987120783;10649.574017039542;10701.090407315154;7081.716731330689;7315.414766818748;7489;7551.811204719926;7814.57019863913;8578.356623253156;8583.914160858312;8612.311015118901;8625.82748345017;8657.523836188819;7504.287092726182;7655.88516172269;7686.3466605336935;7715.652795624098;7743.690252389842;7400.777976660709;7555.9244407555225;7572.886406704014;7663.118741344817;7725.150233474201;6816.318368163343;8130.837383252184;8181.345492360478;8188.549629770181;8207.384446166681;7318.012068370915;7359.77920662381;7363.190049094703;7389.298016688406;7398.741044063648;7435.665395057344;7455.105387353552;7497.437692173;7511.06111736033;7543.321101100927 21:26:40 INFO - PROCESS | 1669 | |4;prototype.html;335.1470507558264;340.0970423525539;343.1387218082612;344.4918744851345;351.11343856761675;6751.763688270911;6791.117154769873;6857.588544687316;6865.279145689706;6907.965460172698;6764.120664313632;6765.154355705537;6775.457963362932;6780.864382712342;6784.253732089466;4892.975535122319;4941.135301322272;4960.280759289901;5046.924296135542;5119.6160287978455;4100.79496025199;5053.494650534935;5057.747112644353;5067.670601410915;5087.364079490064;4968.850934471971;4971.527704868032;4985.750712464381;4987.551120399164;4989.251612258154;4813.7593120343845;4882.780274887638;4936.9879174769085;4991.700497970097;5020.347354843883;3682.171511409938;4278.764667943262;4284.400183974246;4285.828566857322;4287.914241715148;6218.906716399258;6457.2574153971955;6497.93502064978;6537.477001839841;6565.146530950994;5913.556483263729;5921.940780592182;5931.614445061057;5932.436418540232;5949.9107513387335;5728.598998070137;5774.527495488624;7172.354488096044;7183.604901730403;7185.568865868065;4277.379779931924;4575.542445755431;4805.7597120143855;4815.9036819263765;4847.83032593861;6777.390034896883;7000.544964577286;7526.510776799545;7593.240675932418;7596.126445458733;4905.5094490550655;4911.435184953704;4911.9754401227765;4912.975435122801;4914.8034078637165;4971.577415919687;4976.278439626234;4979.253112033224;4984.700917944935;4984.77568509418;5675.801346952837;5883.14694369314;5896.970515147397;5898.64608123514;5901.498372638288;5814;7218.422526197892;7224.133104027445;7238.457115716343;7265.200827908926;1705.1389048530414;1745.3367720266217;1746.2927514356707;1746.7117925542398;1747.8776485645883;906.8730377747121;1063.4629512096496;1111.955521779136;1125.729824842032;1151.2862025544216;6888.207856096513;6987.301269872972;7004.789856304319;7006.789796306119;7010.193827709777;7592.164861865191;7592.316691497792;7613.124490683532;7614.543127412262;7638.656260468292;6285.779997700059;6427.389398007182;6978.302169782981;6998.125234345707;7005.229424763272;3101.255698632312;3928.1554465789422;3996.2207369562657;4012.2376748417705;4012.598740125964;7872.72445464406;7886.44794864354;7888.960555197302;7912.802179945433;7940.602969851484;7794.610269486503;7796.376289896908;7804.414668899855;7809.726659566888;7817.179196184433;7916.604169791487;7917.406194535548;7928.801779955547;7929.563873986986;7934.960325198337;7171.354578087997;7518.398528117628;7532.623368831427;7540.283673051052;7545.6981720730555;6069.453749162597;7338.412926965937;7393.519421237603;7407.925920740724;7429.33136017761;6876.939761806148;7446.515976461513;7904.525728456311;7932.84134317322;7955.801104972422;4722.338872557776;4768.904621907612;4774.594159496342;4774.928376074292;4777.880552986203;7280.376144518684;7350.375218106521;7438.962805185939;7492.2507749226825;7503.962480187564;7171.964140179266;7226.494145409981;7230.530015548972;7242.96378518104;7271.200167981412;7226.783196504113;7235.457340699363;7246.239144889711;7255.274472552703;7255.746048888263;5785.3636100028125;7088.822779430556;7104.218535960937;7106.147262328347;7121.893171602534;7010.509264351446;7258.600776957107;7278.344948954407;7300.744473943387;7319.2314806944505;6861.416779573792;6982.092327997328;6988.580685158907;7146.785596432115;7155.534890232118 21:26:40 INFO - PROCESS | 1669 | |5;yui.html;782.7260458839406;787.7479206653873;802.293981296459;808.1312588966862;809.8420807942454;13001.869981300184;15717.842821571781;15765.029371328585;15825.525234242963;15863;16517.91741041296;17022.744658830095;17035.57411064724;17062.488125356227;17067.573310667238;806.1817255485687;818.2553875972866;959.6017652674132;969.3699095587872;971.8930917599058;988.0218583602253;988.955497002633;992.2508506077901;992.6624947517843;994.3884511025703;1056.461204785561;1056.598492572825;1057.259918057363;1057.4395570347729;1058.6559368205326;848.3340577646549;1011.7420057885266;1035.7876635289747;1038.4600007995875;1039.5841663334652;765.4259305520859;767;767.5279702982625;767.547147183159;769.4421544380335;16071.437499687572;16148.192590370434;16189.433369831999;16194.109323987175;16205.270762815702;12699.936500317532;12788;12818.359082045954;12958.352082395842;13291.933540332333;10126.443045632488;10383.3250838696;11155.888441115567;11289.943550282278;11433.656990290305;3555.6622120898473;3563.429851223818;3590.263995880838;3676.6507181817683;3681.208540163851;81416.18583814145;82293.58853205695;89697.55151224202;89866;89970.10029899683;9489.952550237205;9500.334976551714;9661.372010819274;9742.415455072718;9914.950425247971;22225.222117225818;22246.221382251544;22266;22316.553668926532;22362.888185558968;7718.768436946901;7732.38140948734;7744.8451030980195;7785.766427007199;7832.334251588565;4215.494140703074;4230.2174097792;4254.212970600449;4257.233697934402;4268.381084742695;3728.2357116790995;3770.359038963383;3771.2268984858038;3779.4708740776314;3781.0169355967096;575.0817095262275;669.6015870557011;670.6512613440983;673.3771261583016;676.803726919188;534.398801348483;567.1379503155181;608.881268152706;615.3723202333684;629.2134831460675;7303.48875578715;7898.723544675909;8178.223068808454;8286.46138001028;8411.83176336482;514.5883293365292;542.6988021647944;546.6310240587587;558.8487715306482;565.2087078090706;3815.389537673959;3910.413437984325;3956.3274243378696;3958.129211573449;3972.4041393791163;97746;97788.51105744425;101474.49262753787;102023.95952081065;102205.48897255467;84395;85536.57231713802;85560.14439855522;86246.27507449816;88492.67260991091;64988.675056624415;69720.65139674269;75684;76432;77583.61208193922;7918.49483156076;7997.800054998672;8030.277275045274;8032.879506807286;8353.122922093336;51810.74094629503;53490.73254633858;53698;53798.731006344715;53860.19209711779;67544.66227668831;72083.91874121787;73153;73450.63274683592;74947;10247.487625618838;10298.691039268833;10403.479826008821;10427.217958653284;10479.214058945458;11048.392338421143;11120.721981950515;11287.266327688674;11324.301354796846;11836.053115750547;10419.374837509773;10503.737406564896;10540.156787456832;10701.357918524913;11314.547418103184;10419.479026048819;10544.156467482737;10610.416427096276;10676.306040107369;11376.317420954769;10215.795684086424;10246.897531024595;10414.583416663248;11150.052245559218;11164.385958772345;8758.167974042455;8937;9031.232345250728;9144.63421463134;9158.313126515672;7043.859122817617;8068.47554908929;8082.595870206584;8149.2734214156835;8199.877001845096 21:26:40 INFO - PROCESS | 1669 | __end_tp_report 21:26:40 INFO - PROCESS | 1669 | __start_cc_report 21:26:40 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1195 21:26:40 INFO - PROCESS | 1669 | __end_cc_report 21:26:40 INFO - PROCESS | 1669 | __startTimestamp1463545600608__endTimestamp 21:26:40 INFO - PROCESS | 1669 | 21:26:40 INFO - PROCESS | 1669 | ------- Summary: start ------- 21:26:40 INFO - PROCESS | 1669 | Number of tests: 6 21:26:40 INFO - PROCESS | 1669 | 21:26:40 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5892.05 Median:6190.97 stddev:622.40 (10.1%) stddev-sans-first:596.22 21:26:40 INFO - PROCESS | 1669 | Values: 3535.5 3662.5 3664.6 3668.1 3723.6 5953.5 5965.6 5965.8 5978.4 5980.8 5946.8 5949.2 5987.5 5995.5 5999.2 5166.5 5316.4 5339.1 5356.0 5386.8 5377.9 5397.1 5404.4 5413.1 5415.5 5317.1 5366.9 5379.7 5380.7 5398.4 5385.4 5390.1 5419.6 5420.3 5478.2 5144.3 5334.9 5335.8 5337.7 5344.8 5701.4 5768.8 5772.3 5781.6 5799.2 6231.3 6285.1 6287.2 6291.3 6299.7 5279.6 6215.7 6238.8 6255.0 6281.0 6020.3 6044.2 6045.5 6058.1 6063.8 6334.6 6346.0 6399.9 6413.7 6418.5 6296.4 6313.0 6315.7 6317.8 6334.0 6292.9 6294.3 6313.0 6313.2 6319.2 6191.0 6219.8 6230.5 6297.5 6309.4 6255.6 6276.6 6286.8 6335.0 6338.9 4970.8 4971.3 4980.6 4998.5 5001.7 4413.9 4466.1 4526.0 4533.8 4544.7 6087.4 6087.8 6105.0 6108.9 6111.3 6293.7 6312.9 6328.4 6334.6 6343.7 5843.8 5844.9 5939.4 5954.9 6098.0 5470.7 5488.8 5501.4 5506.8 5512.9 6446.5 6477.2 6485.9 6498.0 6517.0 6194.8 6243.3 6300.1 6344.7 6351.1 6491.8 6514.6 6517.8 6525.4 6527.1 6216.3 6261.0 6304.2 6305.0 6307.2 6213.7 6273.8 6279.6 6296.4 6312.4 6279.3 6285.2 6292.1 6307.2 6315.8 6141.8 6197.6 6267.0 6298.5 6309.6 6139.8 6192.8 6203.6 6208.6 6220.6 6209.8 6278.7 6279.1 6281.6 6282.2 6098.4 6159.8 6182.7 6188.1 6214.9 21:26:40 INFO - PROCESS | 1669 | 21:26:40 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:65285.37 Median:33338.00 stddev:133379.91 (400.1%) stddev-sans-first:133665.37 21:26:40 INFO - PROCESS | 1669 | Values: 495.6 611.3 617.8 627.1 657.8 26979.6 40103.6 40117.8 40299.8 40361.4 43200.0 43800.0 43862.0 43969.0 43984.1 9883.3 10117.3 10120.1 10121.4 10129.4 9921.1 10048.6 10073.9 10075.6 10204.2 10161.4 10232.2 10238.5 10253.8 10256.0 10127.8 10170.9 10199.1 10216.7 10219.9 10050.9 10131.1 10175.9 10202.0 10211.8 42780.9 43140.6 43198.7 43276.8 43277.4 3255.0 3391.2 3444.6 3490.8 3830.2 27711.0 28535.4 28773.3 29093.4 29156.7 2785.8 2787.1 2808.7 2824.5 2832.2 534251.0 538496.3 539756.0 539843.0 541111.0 69257.0 69382.0 69535.0 69581.0 69639.0 79483.2 80278.0 80307.0 80314.6 80454.6 18931.7 18938.7 19064.4 19158.5 19184.2 14615.2 14698.9 14741.9 14748.8 14749.2 22666.0 22690.9 22790.1 23030.4 23093.0 7559.2 8143.4 8726.5 9322.3 9341.5 998.4 1053.1 1087.0 1099.3 1122.8 30523.0 30634.0 31048.0 31484.8 31491.5 1149.1 1166.9 1170.6 1206.2 1213.7 11895.5 15107.7 15128.2 16513.8 16821.5 562352.0 563570.0 564674.0 569206.0 570260.0 62152.0 62696.0 62746.7 62781.4 62924.0 1417.2 1448.6 1531.1 1539.1 1573.3 30238.0 31219.5 31466.7 31613.8 31960.2 50292.7 50656.0 51092.2 51107.7 51162.7 376196.0 378521.0 378829.0 379040.0 379136.0 30534.5 32208.4 32281.2 35684.8 36010.8 44350.0 44389.6 44489.0 44837.6 44839.6 36432.8 36627.5 36676.8 36811.6 37352.6 35843.1 36421.3 36664.5 37053.9 37233.9 31383.0 37240.6 37905.6 38077.0 38571.8 32702.0 32832.8 33153.8 33522.2 33660.2 25614.6 25616.2 25689.0 25750.6 26076.7 21:26:40 INFO - PROCESS | 1669 | 21:26:40 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:38040.72 Median:22219.61 stddev:114365.05 (514.7%) stddev-sans-first:114650.91 21:26:40 INFO - PROCESS | 1669 | Values: 380.8 380.8 384.7 386.7 387.5 20489.4 21455.8 21979.8 22212.2 22227.0 17925.1 22387.9 22791.9 23091.4 23113.5 5910.8 6020.8 6115.5 6132.4 6133.6 5848.4 5991.2 6006.5 6036.8 6054.6 5922.5 5950.4 6062.5 6065.1 6081.8 5874.4 5908.8 6031.8 6069.6 6072.1 5189.2 5271.9 5272.3 5281.7 5281.9 27179.2 27420.7 27493.2 27553.4 27753.0 2303.7 2311.2 2431.1 2551.1 2758.8 12639.7 15206.0 15525.4 15537.8 16100.6 1809.2 1830.4 1843.2 1900.7 1963.7 108113.0 108434.0 109067.0 110811.0 111360.0 10832.0 10944.3 11072.3 11326.7 11395.8 11733.9 14045.7 14076.7 14079.6 14099.2 7874.0 8811.4 9086.4 9109.7 9220.3 9333.4 9399.9 9433.0 9454.9 9498.6 6428.3 6573.0 6577.6 6586.4 6646.0 1582.9 1837.8 1860.1 1868.3 1873.8 1044.8 1047.2 1101.9 1108.5 1163.9 21412.9 22353.8 22531.7 22622.1 22677.7 1051.3 1078.5 1090.2 1130.8 1179.2 11868.5 12302.9 12362.0 12527.4 12584.7 678773.0 679836.0 698247.0 698798.0 702219.0 105409.9 105485.0 133531.3 138223.0 142216.0 1109.3 1121.6 1141.2 1143.9 1148.7 19535.3 20019.9 20300.4 20392.1 20491.5 55543.4 56291.7 56314.4 56961.0 59177.7 77768.8 78213.0 78887.0 79987.0 80400.0 8157.3 8294.0 8426.8 8543.1 8677.7 12469.4 13655.5 13807.6 14149.6 14214.5 10419.4 11215.6 11936.6 12054.4 12059.0 11574.4 11600.7 11704.0 11963.4 12037.2 10497.4 12042.3 12139.5 12223.3 12239.3 8453.4 8603.3 8622.1 8651.3 8702.9 11573.2 12101.1 12267.6 12290.8 12295.8 21:26:40 INFO - PROCESS | 1669 | 21:26:40 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:6329.95 Median:2873.01 stddev:3349.84 (116.6%) stddev-sans-first:3339.77 21:26:40 INFO - PROCESS | 1669 | Values: 1525.2 1669.7 1698.8 2060.6 2107.2 8343.9 8962.7 9179.7 9189.8 9190.6 8809.9 9098.5 9101.2 9101.8 9109.6 2547.6 2566.3 2568.5 2570.5 2578.7 2501.1 2563.6 2567.8 2572.4 2582.1 2546.3 2569.8 2587.5 2593.3 2597.3 2551.2 2567.1 2580.4 2589.8 2593.1 2221.8 2548.0 2575.2 2584.9 2610.1 9119.8 9188.6 9192.5 9204.6 9214.8 5616.8 5787.6 5846.5 5854.7 5856.2 3555.9 3814.0 3870.7 3879.0 3879.0 952.4 1059.4 1081.2 1083.3 1111.1 12517.1 12522.1 12545.5 12560.9 12583.6 5045.2 5069.7 5167.9 5180.1 5184.8 10600.0 10615.2 10638.3 10652.7 10662.5 5795.5 5819.2 5861.5 5867.9 5902.9 4676.4 4831.9 5042.7 5172.1 5211.7 4082.7 4127.8 4144.5 4158.7 4228.9 1348.4 1357.1 1360.0 1368.1 1375.9 1403.6 1434.0 1435.6 1449.5 1457.7 8509.9 8578.1 8578.2 8589.4 8627.5 534.4 538.3 548.5 555.4 557.8 6403.7 6433.1 6475.6 6479.5 6525.1 9474.6 10869.2 11398.3 11431.8 11436.9 10368.8 10916.5 11118.7 11225.2 11229.7 8454.3 8645.3 8688.1 8702.7 8719.0 8479.7 8516.3 8524.7 8579.2 8600.7 9981.5 9999.7 10036.6 10127.9 10248.3 10213.0 10459.5 10618.4 10649.6 10701.1 7081.7 7315.4 7489.0 7551.8 7814.6 8578.4 8583.9 8612.3 8625.8 8657.5 7504.3 7655.9 7686.3 7715.7 7743.7 7400.8 7555.9 7572.9 7663.1 7725.2 6816.3 8130.8 8181.3 8188.5 8207.4 7318.0 7359.8 7363.2 7389.3 7398.7 7435.7 7455.1 7497.4 7511.1 7543.3 21:26:40 INFO - PROCESS | 1669 | 21:26:40 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:5919.02 Median:6779.13 stddev:1892.31 (27.9%) stddev-sans-first:1850.62 21:26:40 INFO - PROCESS | 1669 | Values: 335.1 340.1 343.1 344.5 351.1 6751.8 6791.1 6857.6 6865.3 6908.0 6764.1 6765.2 6775.5 6780.9 6784.3 4893.0 4941.1 4960.3 5046.9 5119.6 4100.8 5053.5 5057.7 5067.7 5087.4 4968.9 4971.5 4985.8 4987.6 4989.3 4813.8 4882.8 4937.0 4991.7 5020.3 3682.2 4278.8 4284.4 4285.8 4287.9 6218.9 6457.3 6497.9 6537.5 6565.1 5913.6 5921.9 5931.6 5932.4 5949.9 5728.6 5774.5 7172.4 7183.6 7185.6 4277.4 4575.5 4805.8 4815.9 4847.8 6777.4 7000.5 7526.5 7593.2 7596.1 4905.5 4911.4 4912.0 4913.0 4914.8 4971.6 4976.3 4979.3 4984.7 4984.8 5675.8 5883.1 5897.0 5898.6 5901.5 5814.0 7218.4 7224.1 7238.5 7265.2 1705.1 1745.3 1746.3 1746.7 1747.9 906.9 1063.5 1112.0 1125.7 1151.3 6888.2 6987.3 7004.8 7006.8 7010.2 7592.2 7592.3 7613.1 7614.5 7638.7 6285.8 6427.4 6978.3 6998.1 7005.2 3101.3 3928.2 3996.2 4012.2 4012.6 7872.7 7886.4 7889.0 7912.8 7940.6 7794.6 7796.4 7804.4 7809.7 7817.2 7916.6 7917.4 7928.8 7929.6 7935.0 7171.4 7518.4 7532.6 7540.3 7545.7 6069.5 7338.4 7393.5 7407.9 7429.3 6876.9 7446.5 7904.5 7932.8 7955.8 4722.3 4768.9 4774.6 4774.9 4777.9 7280.4 7350.4 7439.0 7492.3 7504.0 7172.0 7226.5 7230.5 7243.0 7271.2 7226.8 7235.5 7246.2 7255.3 7255.7 5785.4 7088.8 7104.2 7106.1 7121.9 7010.5 7258.6 7278.3 7300.7 7319.2 6861.4 6982.1 6988.6 7146.8 7155.5 21:26:40 INFO - PROCESS | 1669 | 21:26:40 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:19245.23 Median:53594.37 stddev:27810.96 (51.9%) stddev-sans-first:27854.43 21:26:40 INFO - PROCESS | 1669 | Values: 782.7 787.7 802.3 808.1 809.8 13001.9 15717.8 15765.0 15825.5 15863.0 16517.9 17022.7 17035.6 17062.5 17067.6 806.2 818.3 959.6 969.4 971.9 988.0 989.0 992.3 992.7 994.4 1056.5 1056.6 1057.3 1057.4 1058.7 848.3 1011.7 1035.8 1038.5 1039.6 765.4 767.0 767.5 767.5 769.4 16071.4 16148.2 16189.4 16194.1 16205.3 12699.9 12788.0 12818.4 12958.4 13291.9 10126.4 10383.3 11155.9 11289.9 11433.7 3555.7 3563.4 3590.3 3676.7 3681.2 81416.2 82293.6 89697.6 89866.0 89970.1 9490.0 9500.3 9661.4 9742.4 9915.0 22225.2 22246.2 22266.0 22316.6 22362.9 7718.8 7732.4 7744.8 7785.8 7832.3 4215.5 4230.2 4254.2 4257.2 4268.4 3728.2 3770.4 3771.2 3779.5 3781.0 575.1 669.6 670.7 673.4 676.8 534.4 567.1 608.9 615.4 629.2 7303.5 7898.7 8178.2 8286.5 8411.8 514.6 542.7 546.6 558.8 565.2 3815.4 3910.4 3956.3 3958.1 3972.4 97746.0 97788.5 101474.5 102024.0 102205.5 84395.0 85536.6 85560.1 86246.3 88492.7 64988.7 69720.7 75684.0 76432.0 77583.6 7918.5 7997.8 8030.3 8032.9 8353.1 51810.7 53490.7 53698.0 53798.7 53860.2 67544.7 72083.9 73153.0 73450.6 74947.0 10247.5 10298.7 10403.5 10427.2 10479.2 11048.4 11120.7 11287.3 11324.3 11836.1 10419.4 10503.7 10540.2 10701.4 11314.5 10419.5 10544.2 10610.4 10676.3 11376.3 10215.8 10246.9 10414.6 11150.1 11164.4 8758.2 8937.0 9031.2 9144.6 9158.3 7043.9 8068.5 8082.6 8149.3 8199.9 21:26:40 INFO - PROCESS | 1669 | -------- Summary: end -------- 21:26:40 INFO - PROCESS | 1669 | 21:26:41 INFO - TEST-INFO | 1669: exit 0 21:26:41 INFO - TEST-OK | dromaeo_css | took 1111652ms 21:26:41 INFO - TEST-START | kraken 21:26:41 INFO - operating with platform_type : mac_ 21:26:41 INFO - Initialising browser for kraken test... 21:26:41 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpQbbzEg/profile http://localhost:49272/getInfo.html) 21:26:43 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 21:26:43 INFO - PROCESS | 1674 | colorDepth:24 21:26:43 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 21:26:43 INFO - PROCESS | 1674 | __metrics 21:26:43 INFO - PROCESS | 1674 | 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" 21:26:43 INFO - PROCESS | 1674 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:26:43 INFO - TEST-INFO | 1674: exit 0 21:26:43 INFO - Browser initialized. 21:26:43 INFO - Running cycle 1/1 for kraken test... 21:26:43 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/T/tmpQbbzEg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:26:55 INFO - PROCESS | 1677 | RSS: Main: 156909568 21:26:55 INFO - PROCESS | 1677 | 21:26:58 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 21:26:58 INFO - PROCESS | 1677 | RSS: Main: 213012480 21:26:58 INFO - PROCESS | 1677 | 21:27:01 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 21:27:02 INFO - PROCESS | 1677 | RSS: Main: 223539200 21:27:02 INFO - PROCESS | 1677 | 21:27:05 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 21:27:05 INFO - PROCESS | 1677 | RSS: Main: 214073344 21:27:05 INFO - PROCESS | 1677 | 21:27:07 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 21:27:08 INFO - PROCESS | 1677 | RSS: Main: 215756800 21:27:08 INFO - PROCESS | 1677 | 21:27:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:27:11 INFO - PROCESS | 1677 | RSS: Main: 215826432 21:27:11 INFO - PROCESS | 1677 | 21:27:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 21:27:14 INFO - PROCESS | 1677 | RSS: Main: 215732224 21:27:14 INFO - PROCESS | 1677 | 21:27:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 21:27:17 INFO - PROCESS | 1677 | RSS: Main: 216190976 21:27:17 INFO - PROCESS | 1677 | 21:27:20 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 21:27:21 INFO - PROCESS | 1677 | RSS: Main: 216354816 21:27:21 INFO - PROCESS | 1677 | 21:27:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:27:23 INFO - PROCESS | 1677 | RSS: Main: 250081280 21:27:23 INFO - PROCESS | 1677 | 21:27:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:27:26 INFO - PROCESS | 1677 | RSS: Main: 250359808 21:27:26 INFO - PROCESS | 1677 | 21:27:28 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:27:28 INFO - PROCESS | 1677 | RSS: Main: 254984192 21:27:28 INFO - PROCESS | 1677 | 21:27:31 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:27:31 INFO - PROCESS | 1677 | RSS: Main: 256389120 21:27:31 INFO - PROCESS | 1677 | 21:27:34 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:27:34 INFO - PROCESS | 1677 | RSS: Main: 256503808 21:27:34 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 21:27:36 INFO - PROCESS | 1677 | RSS: Main: 320499712 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | __start_tp_report 21:27:36 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 21:27:36 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 21:27:36 INFO - PROCESS | 1677 | |i|pagename|runs| 21:27:36 INFO - PROCESS | 1677 | |0;ai-astar;110;108;111;109;112;115;156;153;109;112 21:27:36 INFO - PROCESS | 1677 | |1;audio-beat-detection;155;117;151;149;116;138;117;149;147;116 21:27:36 INFO - PROCESS | 1677 | |2;audio-dft;180;185;182;180;185;188;260;221;182;184 21:27:36 INFO - PROCESS | 1677 | |3;audio-fft;73;73;75;141;60;58;58;58;117;55 21:27:36 INFO - PROCESS | 1677 | |4;audio-oscillator;90;93;128;80;77;82;115;77;79;115 21:27:36 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;88;86;89;85;88;87;90;71;116;129 21:27:36 INFO - PROCESS | 1677 | |6;imaging-darkroom;93;93;94;94;94;115;146;93;93;124 21:27:36 INFO - PROCESS | 1677 | |7;imaging-desaturate;86;86;86;103;135;139;87;93;86;70 21:27:36 INFO - PROCESS | 1677 | |8;json-parse-financial;53;57;57;58;57;57;57;57;55;53 21:27:36 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;64;63;71;76;72;69;66;67;66;73 21:27:36 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;80;81;80;81;80;97;99;103;104;84 21:27:36 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;118;119;118;116;129;125;122;125;116;116 21:27:36 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;132;124;131;122;141;123;153;164;163;131 21:27:36 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;64;66;66;67;63;65;55;54;89;89 21:27:36 INFO - PROCESS | 1677 | __end_tp_report 21:27:36 INFO - PROCESS | 1677 | __start_cc_report 21:27:36 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1188 21:27:36 INFO - PROCESS | 1677 | __end_cc_report 21:27:36 INFO - PROCESS | 1677 | __startTimestamp1463545656917__endTimestamp 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | ------- Summary: start ------- 21:27:36 INFO - PROCESS | 1677 | Number of tests: 14 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:119.50 Median:112.00 stddev:18.57 (16.6%) stddev-sans-first:19.37 21:27:36 INFO - PROCESS | 1677 | Values: 110.0 108.0 111.0 109.0 112.0 115.0 156.0 153.0 109.0 112.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:148.00 stddev:16.89 (11.4%) stddev-sans-first:16.38 21:27:36 INFO - PROCESS | 1677 | Values: 155.0 117.0 151.0 149.0 116.0 138.0 117.0 149.0 147.0 116.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:194.70 Median:185.00 stddev:25.94 (14.0%) stddev-sans-first:26.97 21:27:36 INFO - PROCESS | 1677 | Values: 180.0 185.0 182.0 180.0 185.0 188.0 260.0 221.0 182.0 184.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:76.80 Median:59.00 stddev:29.04 (49.2%) stddev-sans-first:30.76 21:27:36 INFO - PROCESS | 1677 | Values: 73.0 73.0 75.0 141.0 60.0 58.0 58.0 58.0 117.0 55.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:79.50 stddev:18.85 (23.7%) stddev-sans-first:19.94 21:27:36 INFO - PROCESS | 1677 | Values: 90.0 93.0 128.0 80.0 77.0 82.0 115.0 77.0 79.0 115.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:87.50 stddev:16.78 (19.2%) stddev-sans-first:17.70 21:27:36 INFO - PROCESS | 1677 | Values: 88.0 86.0 89.0 85.0 88.0 87.0 90.0 71.0 116.0 129.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:93.00 stddev:18.47 (19.9%) stddev-sans-first:19.16 21:27:36 INFO - PROCESS | 1677 | Values: 93.0 93.0 94.0 94.0 94.0 115.0 146.0 93.0 93.0 124.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:86.00 stddev:22.54 (26.2%) stddev-sans-first:23.55 21:27:36 INFO - PROCESS | 1677 | Values: 86.0 86.0 86.0 103.0 135.0 139.0 87.0 93.0 86.0 70.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:57.00 stddev:1.79 (3.1%) stddev-sans-first:1.51 21:27:36 INFO - PROCESS | 1677 | Values: 53.0 57.0 57.0 58.0 57.0 57.0 57.0 57.0 55.0 53.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:70.00 stddev:4.22 (6.0%) stddev-sans-first:4.12 21:27:36 INFO - PROCESS | 1677 | Values: 64.0 63.0 71.0 76.0 72.0 69.0 66.0 67.0 66.0 73.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:81.00 stddev:10.44 (12.9%) stddev-sans-first:10.56 21:27:36 INFO - PROCESS | 1677 | Values: 80.0 81.0 80.0 81.0 80.0 97.0 99.0 103.0 104.0 84.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:120.40 Median:120.50 stddev:4.60 (3.8%) stddev-sans-first:4.80 21:27:36 INFO - PROCESS | 1677 | Values: 118.0 119.0 118.0 116.0 129.0 125.0 122.0 125.0 116.0 116.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.40 Median:136.50 stddev:16.14 (11.8%) stddev-sans-first:16.95 21:27:36 INFO - PROCESS | 1677 | Values: 132.0 124.0 131.0 122.0 141.0 123.0 153.0 164.0 163.0 131.0 21:27:36 INFO - PROCESS | 1677 | 21:27:36 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:66.00 stddev:12.04 (18.2%) stddev-sans-first:12.70 21:27:36 INFO - PROCESS | 1677 | Values: 64.0 66.0 66.0 67.0 63.0 65.0 55.0 54.0 89.0 89.0 21:27:36 INFO - PROCESS | 1677 | -------- Summary: end -------- 21:27:36 INFO - PROCESS | 1677 | 21:27:37 INFO - TEST-INFO | 1677: exit 0 21:27:37 INFO - TEST-OK | kraken | took 56660ms 21:27:37 INFO - SUITE-END | took 1168s 21:27:38 INFO - Completed test suite (00:19:28) 21:27:38 INFO - javascript benchmark 21:27:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3535.4873543336207, 3662.523871896653, 3664.633536646336, 3668.1196512836927, 3723.6276372362768, 5953.464188223059, 5965.642061476315, 5965.821025369235, 5978.372270911557, 5980.79067232647, 5946.762129514825, 5949.196858424111, 5987.461128498434, 5995.520358371331, 5999.190109335238, 5166.535011848933, 5316.415194328631, 5339.145736682131, 5355.973220133894, 5386.78452861885, 5377.865553361177, 5397.136458166694, 5404.351477822667, 5413.079776437995, 5415.512603865652, 5317.149256119022, 5366.865828354284, 5379.731013449312, 5380.677159370451, 5398.379186393556, 5385.380681221671, 5390.137577987503, 5419.647722897999, 5420.295361603005, 5478.178273259043, 5144.254083157921, 5334.91997620036, 5335.759890804884, 5337.6797392156595, 5344.812931547377, 5701.4013528579735, 5768.769249230026, 5772.278465191851, 5781.566382521286, 5799.246098007275, 6231.252249730016, 6285.088662143963, 6287.182666253358, 6291.276503202099, 6299.716512756937, 5279.63042587019, 6215.68921553925, 6238.750449981995, 6255.0, 6280.9685951569945, 6020.307664618581, 6044.214252147192, 6045.546584006217, 6058.091286307089, 6063.818085457399, 6334.619922804554, 6346.0, 6399.872002559922, 6413.711382987777, 6418.518611104186, 6296.370362963667, 6313.0, 6315.747370105236, 6317.842053948688, 6333.968330158412, 6292.874142517124, 6294.276158241769, 6312.968435157886, 6313.210848643919, 6319.210098737658, 6190.969045154746, 6219.782307619212, 6230.501559875199, 6297.527685423612, 6309.369063093745, 6255.624662520172, 6276.623402595859, 6286.779962701284, 6335.049742538656, 6338.904916426257, 4970.801167953241, 4971.254311853251, 4980.626453016038, 4998.500149984972, 5001.724905130252, 4413.933790993137, 4466.129104824593, 4525.954740452619, 4533.795979180944, 4544.7045942013665, 6087.391260873877, 6087.756489740361, 6104.969475152596, 6108.908366374508, 6111.327753947062, 6293.685315734195, 6312.905306420407, 6328.398802113792, 6334.588251763621, 6343.746250149942, 5843.824685259449, 5844.8538786529825, 5939.4357536034, 5954.851128721816, 6097.969510152421, 5470.671759694431, 5488.753006114654, 5501.367342755554, 5506.752196151183, 5512.862178445571, 6446.548741588138, 6477.190351206099, 6485.935140648533, 6498.0, 6516.967415162894, 6194.81415557534, 6243.313235543996, 6300.149479820166, 6344.746210151542, 6351.110844481868, 6491.805245842632, 6514.5765525242605, 6517.837054073686, 6525.3800888915475, 6527.05357723137, 6216.285127210429, 6260.968695156677, 6304.211973503312, 6305.0, 6307.24313082433, 6213.65824879636, 6273.843153921189, 6279.623222606659, 6296.433321001132, 6312.400321969406, 6279.277883043325, 6285.182926219745, 6292.119103325446, 6307.211598550181, 6315.810525684236, 6141.7543298267565, 6197.597156184834, 6266.968665156645, 6298.464630506275, 6309.621422714652, 6139.815805525842, 6192.81421557354, 6203.627782332894, 6208.565400421927, 6220.564560480723, 6209.782657606962, 6278.6860656966055, 6279.120923070683, 6281.59169653971, 6282.1519094921605, 6098.359672234352, 6159.81520554384, 6182.6599537025895, 6188.133661287513, 6214.937850621436], "unit": "score", "name": "dojo.html", "value": 5854.303577036463}, {"lowerIsBetter": false, "replicates": [495.56637941800926, 611.3489134072213, 617.7621615677964, 627.0844566932278, 657.7829316325611, 26979.5953060704, 40103.59896401035, 40117.79941100294, 40299.798501007455, 40361.3945790813, 43200.0, 43800.0, 43862.0, 43969.0, 43984.12031759363, 9883.308168428195, 10117.342372745785, 10120.08919197272, 10121.443320617363, 10129.392236465836, 9921.057499537532, 10048.598056077748, 10073.949630251838, 10075.596976120947, 10204.234682398812, 10161.441120738356, 10232.2325825563, 10238.53926573306, 10253.84619230712, 10256.0, 10127.797444051115, 10170.93205213453, 10199.082082612525, 10216.74458138545, 10219.897801021969, 10050.949745251228, 10131.138853197486, 10175.898241017569, 10202.030807073315, 10211.795764084676, 42780.930476738016, 43140.56859431428, 43198.70403887888, 43276.78361608203, 43277.35083973743, 3255.0, 3391.2369716813764, 3444.621091679913, 3490.7730997485082, 3830.195658911604, 27711.0, 28535.429291414264, 28773.280667983257, 29093.41813163746, 29156.708432915613, 2785.8049936504453, 2787.0941943868324, 2808.705085965985, 2824.477471667748, 2832.2494538947194, 534251.0, 538496.3075184638, 539756.0, 539843.0, 541111.0, 69257.0, 69382.0, 69535.0, 69580.95628565576, 69639.0, 79483.20516794873, 80278.0, 80307.0, 80314.59842700748, 80454.59772701099, 18931.716024259647, 18938.715919261223, 19064.42806715801, 19158.521036973907, 19184.232630694616, 14615.196164210858, 14698.853011469962, 14741.92629036848, 14748.778768318483, 14749.188794616184, 22666.0, 22690.886545567166, 22790.08839646429, 23030.42423939415, 23093.0, 7559.244075592506, 8143.389245806588, 8726.520041397784, 9322.30082743797, 9341.532923353805, 998.4025559105373, 1053.070794174331, 1086.9673909782719, 1099.301943266032, 1122.7529943412437, 30523.0, 30634.0, 31047.99167699599, 31484.842575786974, 31491.527627085612, 1149.0692539043403, 1166.935818529972, 1170.5519027694727, 1206.1617176062728, 1213.7208442058377, 11895.524179032915, 15107.697846043018, 15128.230616302188, 16513.752293715603, 16821.495355139366, 562352.0, 563570.0, 564674.0, 569206.0, 570260.0, 62152.0, 62696.0, 62746.68626656837, 62781.372186278466, 62924.0, 1417.2417756500222, 1448.5509492057497, 1531.1196062264244, 1539.1227000609545, 1573.2527049651324, 30238.00641647375, 31219.531707024868, 31466.685333146375, 31613.8419307902, 31960.200994975312, 50292.74268143326, 50656.0, 51092.23361649652, 51107.744461277456, 51162.74418627883, 376196.0, 378521.0, 378829.0, 379040.0, 379136.0, 30534.541981869846, 32208.35583288368, 32281.22453265093, 35684.821575891954, 36010.819945900104, 44350.0, 44389.556104439835, 44489.0, 44837.551624483334, 44839.55160448484, 36432.81783591171, 36627.45058824067, 36676.81661591675, 36811.63188368082, 37352.62647373491, 35843.13101899235, 36421.27157456889, 36664.45003324899, 37053.888383348545, 37233.88298350945, 31383.0, 37240.627593723715, 37905.62094379021, 38077.0, 38571.8428447147, 32702.037877727413, 32832.83583582067, 33153.83423082869, 33522.16194595155, 33660.158496037795, 25614.615780763677, 25616.231513054638, 25689.0, 25750.613740793528, 26076.73923260743], "unit": "score", "name": "ext.html", "value": 20741.353283291544}, {"lowerIsBetter": false, "replicates": [380.756315957787, 380.801982968856, 384.7191550168377, 386.71962826950454, 387.47303667012875, 20489.38531844045, 21455.785442145574, 21979.780202197973, 22212.222572209997, 22227.0, 17925.10374481277, 22387.888060559715, 22791.886040569814, 23091.42271443215, 23113.537729245407, 5910.822675319737, 6020.819375418744, 6115.541334399938, 6132.417420345082, 6133.601315914473, 5848.385919478457, 5991.1612374267415, 6006.549508786837, 6036.758529658809, 6054.576179667403, 5922.526197904179, 5950.37521060289, 6062.545309101813, 6065.120557519157, 6081.847953801146, 5874.353821079678, 5908.822735317947, 6031.758729650809, 6069.575129740898, 6072.05883088122, 5189.195674670432, 5271.947280527184, 5272.31459910213, 5281.683099014072, 5281.920771188396, 27179.1846244611, 27420.725792742018, 27493.17520474389, 27553.448931021267, 27753.0, 2303.700518932528, 2311.248844125649, 2431.1004928176512, 2551.1453663022908, 2758.8344699318104, 12639.747205055848, 15206.0, 15525.378984840592, 15537.766933496006, 16100.584912263137, 1809.1677828199045, 1830.3868204151624, 1843.1521500109966, 1900.7433996410446, 1963.7348957890645, 108113.0, 108434.0, 109067.0, 110811.0, 111360.0, 10832.0, 10944.288621239593, 11072.280301780358, 11326.660200194008, 11395.829062564067, 11733.941330293294, 14045.719085618435, 14076.71846563063, 14079.577612671636, 14099.224542650252, 7873.960630196927, 8811.383203175845, 9086.363954523253, 9109.726708198765, 9220.308476864262, 9333.439993600407, 9399.906000939904, 9433.00953399897, 9454.90545094554, 9498.620055197714, 6428.325025872309, 6572.967135164294, 6577.572457790228, 6586.37429444202, 6646.0, 1582.8733701303868, 1837.7702787151607, 1860.0792607659025, 1868.2993877296014, 1873.7938826729048, 1044.8402273476436, 1047.177965297238, 1101.944902754859, 1108.4734750993375, 1163.8777928317575, 21412.892935535536, 22353.776462235495, 22531.662025069636, 22622.095116195167, 22677.659835102488, 1051.3008849115406, 1078.5146683992077, 1090.1660229924114, 1130.8247221680654, 1179.2099293473393, 11868.465919033664, 12302.892739653475, 12362.01103911685, 12527.373631318398, 12584.685382865502, 678773.0, 679836.0, 698247.0, 698798.0, 702219.0, 105409.94590054001, 105485.0, 133531.33234333765, 138223.0, 142216.0, 1109.2512554026, 1121.624255874275, 1141.223967701954, 1143.937083460401, 1148.6554033789996, 19535.316263930694, 20019.899900500986, 20300.390988270374, 20392.082356293704, 20491.4877128073, 55543.44456555383, 56291.71854140703, 56314.43685563256, 56961.0, 59177.704111479165, 77768.83346749691, 78213.0, 78887.0, 79987.0, 80400.0, 8157.265846073882, 8294.0, 8426.789330266793, 8543.10297418787, 8677.652893884175, 12469.376531173224, 13655.453781848615, 13807.585772426843, 14149.575512734635, 14214.502492412716, 10419.374837509773, 11215.59805024372, 11936.641900742992, 12054.397280136134, 12058.974987126192, 11574.421278935852, 11600.651980440263, 11704.0, 11963.401829908296, 12037.157398981988, 10497.42264175447, 12042.337671428155, 12139.453724582592, 12223.327716975651, 12239.32683702405, 8453.36599755033, 8603.268722158688, 8622.094680058504, 8651.264642505457, 8702.91297087021, 11573.247738896946, 12101.092418068503, 12267.57063502773, 12290.75418491643, 12295.815562766387], "unit": "score", "name": "jquery.html", "value": 10224.35617943844}, {"lowerIsBetter": false, "replicates": [1525.161161361251, 1669.7244954582495, 1698.8471037606616, 2060.618785524678, 2107.1887323380492, 8343.874841877368, 8962.73111806646, 9179.678711245107, 9189.770255743593, 9190.586423610937, 8809.911900881005, 9098.454092754439, 9101.226395756368, 9101.772455688611, 9109.635614575425, 2547.5796493578537, 2566.2557858221094, 2568.4734629400928, 2570.498752743216, 2578.729233430491, 2501.137107697841, 2563.628273900283, 2567.82025258232, 2572.4083460804027, 2582.122078493312, 2546.3379521324496, 2569.755873192053, 2587.5083734090463, 2593.3386986318465, 2597.324695579144, 2551.170869467421, 2567.075852693029, 2580.406506503505, 2589.8446093234465, 2593.1053786443645, 2221.8222542196586, 2547.961780573293, 2575.188815523104, 2584.8836802343753, 2610.138654244095, 9119.772005699844, 9188.586513606904, 9192.494412807293, 9204.631814727403, 9214.769630759285, 5616.831495055155, 5787.565932555033, 5846.503047240947, 5854.707264636793, 5856.238688970407, 3555.9111022224392, 3814.0464883779055, 3870.6709929656013, 3878.9806050969846, 3879.0, 952.3952290295642, 1059.4384975962753, 1081.1512962324616, 1083.263380900987, 1111.0944580167138, 12517.12380133391, 12522.060845436537, 12545.498180072786, 12560.874391256062, 12583.559575414909, 5045.234168041229, 5069.670471419346, 5167.9224811627855, 5180.093483640348, 5184.844454666366, 10600.0, 10615.150787936947, 10638.308509946828, 10652.733681658021, 10662.466876656135, 5795.536357091422, 5819.214406055213, 5861.531077513874, 5867.882642347215, 5902.911456328158, 4676.392069031072, 4831.879203019882, 5042.722650254272, 5172.069027574998, 5211.661242019257, 4082.7305583677066, 4127.814248358831, 4144.544100148981, 4158.688098392572, 4228.873133805991, 1348.366267854107, 1357.097530142464, 1359.9796003059962, 1368.0765483298733, 1375.8511642778392, 1403.585942147069, 1433.9856601433853, 1435.648266174794, 1449.4926775628446, 1457.6720237946377, 8509.914900850912, 8578.142185781371, 8578.227959483676, 8589.441686290496, 8627.482351058956, 534.3507638219581, 538.251829956361, 548.528265691505, 555.3918459287074, 557.7824648387133, 6403.679816009181, 6433.131527243762, 6475.611463312216, 6479.514036447191, 6525.11910892033, 9474.573644186028, 10869.239153259354, 11398.316101033965, 11431.828522572168, 11436.94281528587, 10368.792624147474, 10916.50875710579, 11118.666440006813, 11225.158113141548, 11229.66311010671, 8454.281386082252, 8645.308375330083, 8688.087750786202, 8702.738917832476, 8719.0, 8479.703210387606, 8516.318694504302, 8524.74425767228, 8579.184977427134, 8600.741977740678, 9981.500924953869, 9999.700008999742, 10036.598536058767, 10127.949360253151, 10248.333858299187, 10212.978702130025, 10459.47702614851, 10618.415987120783, 10649.574017039542, 10701.090407315154, 7081.716731330689, 7315.414766818748, 7489.0, 7551.811204719926, 7814.57019863913, 8578.356623253156, 8583.914160858312, 8612.311015118901, 8625.82748345017, 8657.523836188819, 7504.287092726182, 7655.88516172269, 7686.3466605336935, 7715.652795624098, 7743.690252389842, 7400.777976660709, 7555.9244407555225, 7572.886406704014, 7663.118741344817, 7725.150233474201, 6816.318368163343, 8130.837383252184, 8181.345492360478, 8188.549629770181, 8207.384446166681, 7318.012068370915, 7359.77920662381, 7363.190049094703, 7389.298016688406, 7398.741044063648, 7435.665395057344, 7455.105387353552, 7497.437692173, 7511.06111736033, 7543.321101100927], "unit": "score", "name": "mootools.html", "value": 5080.345249208042}, {"lowerIsBetter": false, "replicates": [335.1470507558264, 340.0970423525539, 343.1387218082612, 344.4918744851345, 351.11343856761675, 6751.763688270911, 6791.117154769873, 6857.588544687316, 6865.279145689706, 6907.965460172698, 6764.120664313632, 6765.154355705537, 6775.457963362932, 6780.864382712342, 6784.253732089466, 4892.975535122319, 4941.135301322272, 4960.280759289901, 5046.924296135542, 5119.6160287978455, 4100.79496025199, 5053.494650534935, 5057.747112644353, 5067.670601410915, 5087.364079490064, 4968.850934471971, 4971.527704868032, 4985.750712464381, 4987.551120399164, 4989.251612258154, 4813.7593120343845, 4882.780274887638, 4936.9879174769085, 4991.700497970097, 5020.347354843883, 3682.171511409938, 4278.764667943262, 4284.400183974246, 4285.828566857322, 4287.914241715148, 6218.906716399258, 6457.2574153971955, 6497.93502064978, 6537.477001839841, 6565.146530950994, 5913.556483263729, 5921.940780592182, 5931.614445061057, 5932.436418540232, 5949.9107513387335, 5728.598998070137, 5774.527495488624, 7172.354488096044, 7183.604901730403, 7185.568865868065, 4277.379779931924, 4575.542445755431, 4805.7597120143855, 4815.9036819263765, 4847.83032593861, 6777.390034896883, 7000.544964577286, 7526.510776799545, 7593.240675932418, 7596.126445458733, 4905.5094490550655, 4911.435184953704, 4911.9754401227765, 4912.975435122801, 4914.8034078637165, 4971.577415919687, 4976.278439626234, 4979.253112033224, 4984.700917944935, 4984.77568509418, 5675.801346952837, 5883.14694369314, 5896.970515147397, 5898.64608123514, 5901.498372638288, 5814.0, 7218.422526197892, 7224.133104027445, 7238.457115716343, 7265.200827908926, 1705.1389048530414, 1745.3367720266217, 1746.2927514356707, 1746.7117925542398, 1747.8776485645883, 906.8730377747121, 1063.4629512096496, 1111.955521779136, 1125.729824842032, 1151.2862025544216, 6888.207856096513, 6987.301269872972, 7004.789856304319, 7006.789796306119, 7010.193827709777, 7592.164861865191, 7592.316691497792, 7613.124490683532, 7614.543127412262, 7638.656260468292, 6285.779997700059, 6427.389398007182, 6978.302169782981, 6998.125234345707, 7005.229424763272, 3101.255698632312, 3928.1554465789422, 3996.2207369562657, 4012.2376748417705, 4012.598740125964, 7872.72445464406, 7886.44794864354, 7888.960555197302, 7912.802179945433, 7940.602969851484, 7794.610269486503, 7796.376289896908, 7804.414668899855, 7809.726659566888, 7817.179196184433, 7916.604169791487, 7917.406194535548, 7928.801779955547, 7929.563873986986, 7934.960325198337, 7171.354578087997, 7518.398528117628, 7532.623368831427, 7540.283673051052, 7545.6981720730555, 6069.453749162597, 7338.412926965937, 7393.519421237603, 7407.925920740724, 7429.33136017761, 6876.939761806148, 7446.515976461513, 7904.525728456311, 7932.84134317322, 7955.801104972422, 4722.338872557776, 4768.904621907612, 4774.594159496342, 4774.928376074292, 4777.880552986203, 7280.376144518684, 7350.375218106521, 7438.962805185939, 7492.2507749226825, 7503.962480187564, 7171.964140179266, 7226.494145409981, 7230.530015548972, 7242.96378518104, 7271.200167981412, 7226.783196504113, 7235.457340699363, 7246.239144889711, 7255.274472552703, 7255.746048888263, 5785.3636100028125, 7088.822779430556, 7104.218535960937, 7106.147262328347, 7121.893171602534, 7010.509264351446, 7258.600776957107, 7278.344948954407, 7300.744473943387, 7319.2314806944505, 6861.416779573792, 6982.092327997328, 6988.580685158907, 7146.785596432115, 7155.534890232118], "unit": "score", "name": "prototype.html", "value": 5288.723832355032}, {"lowerIsBetter": false, "replicates": [782.7260458839406, 787.7479206653873, 802.293981296459, 808.1312588966862, 809.8420807942454, 13001.869981300184, 15717.842821571781, 15765.029371328585, 15825.525234242963, 15863.0, 16517.91741041296, 17022.744658830095, 17035.57411064724, 17062.488125356227, 17067.573310667238, 806.1817255485687, 818.2553875972866, 959.6017652674132, 969.3699095587872, 971.8930917599058, 988.0218583602253, 988.955497002633, 992.2508506077901, 992.6624947517843, 994.3884511025703, 1056.461204785561, 1056.598492572825, 1057.259918057363, 1057.4395570347729, 1058.6559368205326, 848.3340577646549, 1011.7420057885266, 1035.7876635289747, 1038.4600007995875, 1039.5841663334652, 765.4259305520859, 767.0, 767.5279702982625, 767.547147183159, 769.4421544380335, 16071.437499687572, 16148.192590370434, 16189.433369831999, 16194.109323987175, 16205.270762815702, 12699.936500317532, 12788.0, 12818.359082045954, 12958.352082395842, 13291.933540332333, 10126.443045632488, 10383.3250838696, 11155.888441115567, 11289.943550282278, 11433.656990290305, 3555.6622120898473, 3563.429851223818, 3590.263995880838, 3676.6507181817683, 3681.208540163851, 81416.18583814145, 82293.58853205695, 89697.55151224202, 89866.0, 89970.10029899683, 9489.952550237205, 9500.334976551714, 9661.372010819274, 9742.415455072718, 9914.950425247971, 22225.222117225818, 22246.221382251544, 22266.0, 22316.553668926532, 22362.888185558968, 7718.768436946901, 7732.38140948734, 7744.8451030980195, 7785.766427007199, 7832.334251588565, 4215.494140703074, 4230.2174097792, 4254.212970600449, 4257.233697934402, 4268.381084742695, 3728.2357116790995, 3770.359038963383, 3771.2268984858038, 3779.4708740776314, 3781.0169355967096, 575.0817095262275, 669.6015870557011, 670.6512613440983, 673.3771261583016, 676.803726919188, 534.398801348483, 567.1379503155181, 608.881268152706, 615.3723202333684, 629.2134831460675, 7303.48875578715, 7898.723544675909, 8178.223068808454, 8286.46138001028, 8411.83176336482, 514.5883293365292, 542.6988021647944, 546.6310240587587, 558.8487715306482, 565.2087078090706, 3815.389537673959, 3910.413437984325, 3956.3274243378696, 3958.129211573449, 3972.4041393791163, 97746.0, 97788.51105744425, 101474.49262753787, 102023.95952081065, 102205.48897255467, 84395.0, 85536.57231713802, 85560.14439855522, 86246.27507449816, 88492.67260991091, 64988.675056624415, 69720.65139674269, 75684.0, 76432.0, 77583.61208193922, 7918.49483156076, 7997.800054998672, 8030.277275045274, 8032.879506807286, 8353.122922093336, 51810.74094629503, 53490.73254633858, 53698.0, 53798.731006344715, 53860.19209711779, 67544.66227668831, 72083.91874121787, 73153.0, 73450.63274683592, 74947.0, 10247.487625618838, 10298.691039268833, 10403.479826008821, 10427.217958653284, 10479.214058945458, 11048.392338421143, 11120.721981950515, 11287.266327688674, 11324.301354796846, 11836.053115750547, 10419.374837509773, 10503.737406564896, 10540.156787456832, 10701.357918524913, 11314.547418103184, 10419.479026048819, 10544.156467482737, 10610.416427096276, 10676.306040107369, 11376.317420954769, 10215.795684086424, 10246.897531024595, 10414.583416663248, 11150.052245559218, 11164.385958772345, 8758.167974042455, 8937.0, 9031.232345250728, 9144.63421463134, 9158.313126515672, 7043.859122817617, 8068.47554908929, 8082.595870206584, 8149.2734214156835, 8199.877001845096], "unit": "score", "name": "yui.html", "value": 7069.0265812237185}], "name": "dromaeo_css", "value": 7860.156450802916}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 108.0, 111.0, 109.0, 112.0, 115.0, 156.0, 153.0, 109.0, 112.0], "unit": "score", "name": "ai-astar", "value": 119.5}, {"lowerIsBetter": true, "replicates": [155.0, 117.0, 151.0, 149.0, 116.0, 138.0, 117.0, 149.0, 147.0, 116.0], "unit": "score", "name": "audio-beat-detection", "value": 135.5}, {"lowerIsBetter": true, "replicates": [180.0, 185.0, 182.0, 180.0, 185.0, 188.0, 260.0, 221.0, 182.0, 184.0], "unit": "score", "name": "audio-dft", "value": 194.7}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 75.0, 141.0, 60.0, 58.0, 58.0, 58.0, 117.0, 55.0], "unit": "score", "name": "audio-fft", "value": 76.8}, {"lowerIsBetter": true, "replicates": [90.0, 93.0, 128.0, 80.0, 77.0, 82.0, 115.0, 77.0, 79.0, 115.0], "unit": "score", "name": "audio-oscillator", "value": 93.6}, {"lowerIsBetter": true, "replicates": [88.0, 86.0, 89.0, 85.0, 88.0, 87.0, 90.0, 71.0, 116.0, 129.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 92.9}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 94.0, 94.0, 94.0, 115.0, 146.0, 93.0, 93.0, 124.0], "unit": "score", "name": "imaging-darkroom", "value": 103.9}, {"lowerIsBetter": true, "replicates": [86.0, 86.0, 86.0, 103.0, 135.0, 139.0, 87.0, 93.0, 86.0, 70.0], "unit": "score", "name": "imaging-desaturate", "value": 97.1}, {"lowerIsBetter": true, "replicates": [53.0, 57.0, 57.0, 58.0, 57.0, 57.0, 57.0, 57.0, 55.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 56.1}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 71.0, 76.0, 72.0, 69.0, 66.0, 67.0, 66.0, 73.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.7}, {"lowerIsBetter": true, "replicates": [80.0, 81.0, 80.0, 81.0, 80.0, 97.0, 99.0, 103.0, 104.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 88.9}, {"lowerIsBetter": true, "replicates": [118.0, 119.0, 118.0, 116.0, 129.0, 125.0, 122.0, 125.0, 116.0, 116.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.4}, {"lowerIsBetter": true, "replicates": [132.0, 124.0, 131.0, 122.0, 141.0, 123.0, 153.0, 164.0, 163.0, 131.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 138.4}, {"lowerIsBetter": true, "replicates": [64.0, 66.0, 66.0, 67.0, 63.0, 65.0, 55.0, 54.0, 89.0, 89.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.8}], "name": "kraken", "value": 1454.3000000000002}]} 21:27:38 INFO - Return code: 0 21:27:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:27:38 INFO - # TBPL SUCCESS # 21:27:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:27:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:27:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:27:38 INFO - 2016-05-17 21:27:38.201 screenresolution[1681:10192] starting screenresolution argv=screenresolution get 21:27:38 INFO - 2016-05-17 21:27:38.203 screenresolution[1681:10192] Display 0: 1600x1200x32@60 21:27:38 INFO - 2016-05-17 21:27:38.210 screenresolution[1682:10195] starting screenresolution argv=screenresolution list 21:27:38 INFO - Available Modes on Display 0 21:27:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:27:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:27:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:27:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:27:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:27:38 INFO - Intel Iris: 21:27:38 INFO - Chipset Model: Intel Iris 21:27:38 INFO - Type: GPU 21:27:38 INFO - Bus: Built-In 21:27:38 INFO - VRAM (Dynamic, Max): 1536 MB 21:27:38 INFO - Vendor: Intel (0x8086) 21:27:38 INFO - Device ID: 0x0a2e 21:27:38 INFO - Revision ID: 0x0009 21:27:38 INFO - Displays: 21:27:38 INFO - Display: 21:27:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:27:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:27:38 INFO - Display Serial Number: 1600x1200 60 21:27:38 INFO - Main Display: Yes 21:27:38 INFO - Mirror: Off 21:27:38 INFO - Online: Yes 21:27:38 INFO - Rotation: Supported 21:27:38 INFO - Return code: 0 21:27:38 INFO - Running post-action listener: _resource_record_post_action 21:27:38 INFO - Running post-run listener: _resource_record_post_run 21:27:39 INFO - Total resource usage - Wall time: 1193s; CPU: 52.0%; Read bytes: 31333888; Write bytes: 508044800; Read time: 191; Write time: 1565 21:27:39 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 198560256; Write bytes: 199112192; Read time: 18004; Write time: 427 21:27:39 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 30412288; Write bytes: 300040192; Read time: 179; Write time: 1089 21:27:39 INFO - Running post-run listener: _upload_blobber_files 21:27:39 INFO - Blob upload gear active. 21:27:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:27:39 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 21:27:39 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'] 21:27:39 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 21:27:39 INFO - (blobuploader) - INFO - Open directory for files ... 21:27:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:27:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:27:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:27:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:27:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:27:40 INFO - (blobuploader) - INFO - Done attempting. 21:27:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:27:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:27:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:27:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:27:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:27:41 INFO - (blobuploader) - INFO - Done attempting. 21:27:41 INFO - (blobuploader) - INFO - Iteration through files over. 21:27:41 INFO - Return code: 0 21:27:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:27:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:27:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c813b0a491b072f8499cab0ff0cc02fe9bbc4bcfef786f0cb5039dc9d4fe0f51209e7f7055088444933d1d689c3a0ae555945f2d97ef570bc3ecdc69e5231cef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/407ed89bb624cb83edaa77d281278c5db0291ec6e6376386dbd696c9426131b0948b5ea6e32db8ef4c104ad4c3aa9a536cd49acca0ab357216a3c37d96cae9d0"} 21:27:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:27:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:27:41 INFO - Contents: 21:27:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c813b0a491b072f8499cab0ff0cc02fe9bbc4bcfef786f0cb5039dc9d4fe0f51209e7f7055088444933d1d689c3a0ae555945f2d97ef570bc3ecdc69e5231cef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/407ed89bb624cb83edaa77d281278c5db0291ec6e6376386dbd696c9426131b0948b5ea6e32db8ef4c104ad4c3aa9a536cd49acca0ab357216a3c37d96cae9d0"} 21:27:41 INFO - Running post-run listener: copy_logs_to_upload_dir 21:27:41 INFO - Copying logs to upload dir... 21:27:41 INFO - mkdir: /builds/slave/test/build/upload/logs 21:27:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.108720 ========= master_lag: 18.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-17 21:27:59.882861) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 21:27:59.886553) ========= 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.akB56jrxiV/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.Vzuo5bmV4D/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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/c813b0a491b072f8499cab0ff0cc02fe9bbc4bcfef786f0cb5039dc9d4fe0f51209e7f7055088444933d1d689c3a0ae555945f2d97ef570bc3ecdc69e5231cef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/407ed89bb624cb83edaa77d281278c5db0291ec6e6376386dbd696c9426131b0948b5ea6e32db8ef4c104ad4c3aa9a536cd49acca0ab357216a3c37d96cae9d0"} build_url:https://queue.taskcluster.net/v1/task/Flwtilp2TD6jk5yeMErzJw/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011139 build_url: 'https://queue.taskcluster.net/v1/task/Flwtilp2TD6jk5yeMErzJw/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/c813b0a491b072f8499cab0ff0cc02fe9bbc4bcfef786f0cb5039dc9d4fe0f51209e7f7055088444933d1d689c3a0ae555945f2d97ef570bc3ecdc69e5231cef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/407ed89bb624cb83edaa77d281278c5db0291ec6e6376386dbd696c9426131b0948b5ea6e32db8ef4c104ad4c3aa9a536cd49acca0ab357216a3c37d96cae9d0"}' ========= master_lag: 0.97 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 21:28:00.863912) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 15 secs) (at 2016-05-17 21:28:00.864348) ========= 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.akB56jrxiV/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.Vzuo5bmV4D/Listeners TMPDIR=/var/folders/ql/g2ft0_gn4vs3nx5yvzcx9bmh00000w/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.005083 ========= master_lag: 15.15 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 15 secs) (at 2016-05-17 21:28:16.020986) ========= ========= Started reboot skipped (results: 3, elapsed: 4 secs) (at 2016-05-17 21:28:16.021272) ========= ========= Finished reboot skipped (results: 3, elapsed: 4 secs) (at 2016-05-17 21:28:20.255144) ========= ========= Total master_lag: 36.57 =========