builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0032 starttime: 1462833695.14 results: success (0) buildid: 20160509134609 builduid: 09aef832063f45eb8080f23855736164 revision: 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:35.144903) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:35.145345) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:35.145617) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:35.165321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:35.165701) ========= 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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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-09 15:41:35-- 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% 8.53M=0.001s 2016-05-09 15:41:35 (8.53 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123125 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:35.320485) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:35.320795) ========= 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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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.035086 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:35.371013) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 15:41:35.371371) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 --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 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.O6HX4Efzf5/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.9VLFBjmjJL/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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-09 15:41:35,444 truncating revision to first 12 chars 2016-05-09 15:41:35,444 Setting DEBUG logging. 2016-05-09 15:41:35,444 attempt 1/10 2016-05-09 15:41:35,444 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2b710898eaf6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 15:41:36,476 unpacking tar archive at: mozilla-beta-2b710898eaf6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.277876 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 15:41:36.665681) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:36.665993) ========= script_repo_revision: 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:36.666375) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:36.666633) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 15:41:36.681356) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-09 15:41:36.681744) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 15:41:36 INFO - MultiFileLogger online at 20160509 15:41:36 in /builds/slave/test 15:41:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:41:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:41:36 INFO - {'append_to_log': False, 15:41:36 INFO - 'base_work_dir': '/builds/slave/test', 15:41:36 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:41:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:41:36 INFO - 'branch': 'Mozilla-Beta', 15:41:36 INFO - 'buildbot_json_path': 'buildprops.json', 15:41:36 INFO - 'config_files': ('talos/mac_config.py',), 15:41:36 INFO - 'default_actions': ('clobber', 15:41:36 INFO - 'read-buildbot-config', 15:41:36 INFO - 'download-and-extract', 15:41:36 INFO - 'populate-webroot', 15:41:36 INFO - 'create-virtualenv', 15:41:36 INFO - 'install', 15:41:36 INFO - 'run-tests'), 15:41:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:41:36 INFO - 'download_minidump_stackwalk': True, 15:41:36 INFO - 'download_symbols': 'ondemand', 15:41:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:41:36 INFO - 'tooltool.py': '/tools/tooltool.py', 15:41:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:41:36 INFO - '/tools/misc-python/virtualenv.py')}, 15:41:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:41:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:41:36 INFO - 'installer_path': 'installer.exe', 15:41:36 INFO - 'log_level': 'info', 15:41:36 INFO - 'log_name': 'talos', 15:41:36 INFO - 'log_to_console': True, 15:41:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:41:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:41:36 INFO - 'opt_config_files': (), 15:41:36 INFO - 'pip_index': False, 15:41:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:41:36 INFO - 'cmd': ('bash', 15:41:36 INFO - '-c', 15:41:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:41:36 INFO - 'enabled': True, 15:41:36 INFO - 'halt_on_failure': False, 15:41:36 INFO - 'name': 'check_screen_resolution'},), 15:41:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:41:36 INFO - 'cmd': ('bash', 15:41:36 INFO - '-c', 15:41:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:41:36 INFO - 'enabled': True, 15:41:36 INFO - 'halt_on_failure': False, 15:41:36 INFO - 'name': 'check_screen_resolution'},), 15:41:36 INFO - 'run_cmd_checks_enabled': True, 15:41:36 INFO - 'sps_profile': False, 15:41:36 INFO - 'sps_profile_interval': 0, 15:41:36 INFO - 'suite': 'dromaeojs-e10s', 15:41:36 INFO - 'system_bits': '32', 15:41:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:41:36 INFO - 'title': 't-yosemite-r7-0032', 15:41:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:41:36 INFO - 'use_talos_json': True, 15:41:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:41:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:41:36 INFO - 'work_dir': 'build'} 15:41:36 INFO - ##### 15:41:36 INFO - ##### Running clobber step. 15:41:36 INFO - ##### 15:41:36 INFO - Running pre-action listener: _resource_record_pre_action 15:41:36 INFO - Running main action method: clobber 15:41:36 INFO - rmtree: /builds/slave/test/build 15:41:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:41:38 INFO - Running post-action listener: _resource_record_post_action 15:41:38 INFO - ##### 15:41:38 INFO - ##### Running read-buildbot-config step. 15:41:38 INFO - ##### 15:41:38 INFO - Running pre-action listener: _resource_record_pre_action 15:41:38 INFO - Running main action method: read_buildbot_config 15:41:38 INFO - Using buildbot properties: 15:41:38 INFO - { 15:41:38 INFO - "project": "", 15:41:38 INFO - "product": "firefox", 15:41:38 INFO - "script_repo_revision": "production", 15:41:38 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:41:38 INFO - "repository": "", 15:41:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:41:38 INFO - "buildid": "20160509134609", 15:41:38 INFO - "pgo_build": "False", 15:41:38 INFO - "basedir": "/builds/slave/test", 15:41:38 INFO - "buildnumber": 0, 15:41:38 INFO - "slavename": "t-yosemite-r7-0032", 15:41:38 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:41:38 INFO - "platform": "yosemite_r7", 15:41:38 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:41:38 INFO - "branch": "mozilla-beta", 15:41:38 INFO - "repo_path": "releases/mozilla-beta", 15:41:38 INFO - "revision": "2b710898eaf609aa9dc45fd75e03bc134d20f6b8", 15:41:38 INFO - "stage_platform": "macosx64", 15:41:38 INFO - "builduid": "09aef832063f45eb8080f23855736164", 15:41:38 INFO - "slavebuilddir": "test" 15:41:38 INFO - } 15:41:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 15:41:38 INFO - Running post-action listener: _resource_record_post_action 15:41:38 INFO - ##### 15:41:38 INFO - ##### Running download-and-extract step. 15:41:38 INFO - ##### 15:41:38 INFO - Running pre-action listener: _resource_record_pre_action 15:41:38 INFO - Running main action method: download_and_extract 15:41:38 INFO - mkdir: /builds/slave/test/build/tests 15:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:38 INFO - https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:41:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 15:41:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 15:41:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/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 15:41:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/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 15:41:39 INFO - Downloaded 1241 bytes. 15:41:39 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 15:41:39 INFO - Using the following test package requirements: 15:41:39 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 15:41:39 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:39 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 15:41:39 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:39 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 15:41:39 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:41:39 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:39 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 15:41:39 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 15:41:39 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:39 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 15:41:39 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:39 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 15:41:39 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:39 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 15:41:39 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 15:41:39 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 15:41:39 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 15:41:39 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 15:41:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:39 INFO - https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:41:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:41:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:41:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/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 15:41:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/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 15:41:40 INFO - Downloaded 17747473 bytes. 15:41:40 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'] 15:41:40 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 15:41:44 INFO - Return code: 0 15:41:44 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 15:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:41:44 INFO - https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:41:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:41:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 15:41:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/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 15:41:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/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 15:41:45 INFO - Downloaded 17747473 bytes. 15:41:45 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'] 15:41:45 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 15:41:48 INFO - Return code: 0 15:41:48 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')]} 15:41:48 INFO - https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:41:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 15:41:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 15:41:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/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 15:41:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/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 15:41:49 INFO - Downloaded 10845194 bytes. 15:41:49 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'] 15:41:49 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 15:41:50 INFO - Return code: 0 15:41: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')]} 15:41:50 INFO - https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 15:41:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:41:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:41:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 15:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/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 15:41:52 INFO - Downloaded 88333205 bytes. 15:41:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:41:52 INFO - mkdir: /builds/slave/test/properties 15:41:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:41:52 INFO - Writing to file /builds/slave/test/properties/build_url 15:41:52 INFO - Contents: 15:41:52 INFO - build_url:https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 15:41:53 INFO - Running post-action listener: _resource_record_post_action 15:41:53 INFO - Running post-action listener: set_extra_try_arguments 15:41:53 INFO - ##### 15:41:53 INFO - ##### Running populate-webroot step. 15:41:53 INFO - ##### 15:41:53 INFO - Running pre-action listener: _resource_record_pre_action 15:41:53 INFO - Running main action method: populate_webroot 15:41:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:41:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:41:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:41:53 INFO - '--tppagecycles', 15:41:53 INFO - '7'], 15:41:53 INFO - 'tests': ['tsvgm']}}, 15:41:53 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:41:53 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:41:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:41:53 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 15:41:53 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:41:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:41:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:41:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:53 INFO - 'talos_options': ['--e10s'], 15:41:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:41:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:53 INFO - 'tests': ['damp', 'tps']}, 15:41:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:53 INFO - 'talos_options': ['--e10s'], 15:41:53 INFO - 'tests': ['damp', 'tps']}, 15:41:53 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:41:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:41:53 INFO - 'other': {'tests': ['a11yr', 15:41:53 INFO - 'ts_paint', 15:41:53 INFO - 'tpaint', 15:41:53 INFO - 'sessionrestore', 15:41:53 INFO - 'sessionrestore_no_auto_restore', 15:41:53 INFO - 'tabpaint']}, 15:41:53 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:41:53 INFO - 'tests': ['a11yr', 15:41:53 INFO - 'ts_paint', 15:41:53 INFO - 'tpaint', 15:41:53 INFO - 'sessionrestore', 15:41:53 INFO - 'sessionrestore_no_auto_restore', 15:41:53 INFO - 'tabpaint']}, 15:41:53 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:41:53 INFO - 'tests': ['a11yr', 15:41:53 INFO - 'ts_paint', 15:41:53 INFO - 'tpaint', 15:41:53 INFO - 'sessionrestore', 15:41:53 INFO - 'sessionrestore_no_auto_restore', 15:41:53 INFO - 'tabpaint']}, 15:41:53 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:41:53 INFO - 'tests': ['a11yr', 15:41:53 INFO - 'ts_paint', 15:41:53 INFO - 'tpaint', 15:41:53 INFO - 'sessionrestore', 15:41:53 INFO - 'sessionrestore_no_auto_restore', 15:41:53 INFO - 'tabpaint']}, 15:41:53 INFO - 'other_l64': {'tests': ['a11yr', 15:41:53 INFO - 'ts_paint', 15:41:53 INFO - 'tpaint', 15:41:53 INFO - 'sessionrestore', 15:41:53 INFO - 'sessionrestore_no_auto_restore', 15:41:53 INFO - 'tabpaint']}, 15:41:53 INFO - 'other_nol64': {'tests': ['a11yr', 15:41:53 INFO - 'ts_paint', 15:41:53 INFO - 'tpaint', 15:41:53 INFO - 'sessionrestore', 15:41:53 INFO - 'sessionrestore_no_auto_restore', 15:41:53 INFO - 'tabpaint']}, 15:41:53 INFO - 'svgr': {'tests': ['tsvgx', 15:41:53 INFO - 'tsvgr_opacity', 15:41:53 INFO - 'tart', 15:41:53 INFO - 'tscrollx', 15:41:53 INFO - 'cart']}, 15:41:53 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:41:53 INFO - 'tests': ['tsvgx', 15:41:53 INFO - 'tsvgr_opacity', 15:41:53 INFO - 'tart', 15:41:53 INFO - 'tscrollx', 15:41:53 INFO - 'cart']}, 15:41:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:53 INFO - 'tests': ['tp5o']}, 15:41:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:53 INFO - 'talos_options': ['--e10s'], 15:41:53 INFO - 'tests': ['tp5o']}, 15:41:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:53 INFO - 'talos_options': ['--xperf_path', 15:41:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:41:53 INFO - 'tests': ['tp5n']}, 15:41:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:41:53 INFO - 'talos_options': ['--e10s', 15:41:53 INFO - '--xperf_path', 15:41:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:41:53 INFO - 'tests': ['tp5n']}}, 15:41:53 INFO - 'talos.zip': {'path': '', 15:41:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:41:53 INFO - Running post-action listener: _resource_record_post_action 15:41:53 INFO - ##### 15:41:53 INFO - ##### Running create-virtualenv step. 15:41:53 INFO - ##### 15:41:53 INFO - Running pre-action listener: _resource_record_pre_action 15:41:53 INFO - Running main action method: create_virtualenv 15:41:53 INFO - Creating virtualenv /builds/slave/test/build/venv 15:41:53 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 15:41:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:41:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:41:53 INFO - Using real prefix '/tools/python27' 15:41:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:41:54 INFO - Installing distribute.............................................................................................................................................................................................done. 15:41:57 INFO - Installing pip.................done. 15:41:57 INFO - Return code: 0 15:41:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:41:57 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')]} 15:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:57 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')]} 15:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:57 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 0x10d387a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d38e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa790cea9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bd30>, '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 0x10d08fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d222b20>, '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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 15:41:57 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 15:41:57 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 15:41:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O6HX4Efzf5/Render', 15:41:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:57 INFO - 'HOME': '/Users/cltbld', 15:41:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:57 INFO - 'LOGNAME': 'cltbld', 15:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:57 INFO - 'MOZ_NO_REMOTE': '1', 15:41:57 INFO - 'NO_EM_RESTART': '1', 15:41:57 INFO - 'PAGER': '/bin/cat', 15:41:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:57 INFO - 'PWD': '/builds/slave/test', 15:41:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:57 INFO - 'SHELL': '/bin/bash', 15:41:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9VLFBjmjJL/Listeners', 15:41:57 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 15:41:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:57 INFO - 'USER': 'cltbld', 15:41:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:57 INFO - 'XPC_FLAGS': '0x0', 15:41:57 INFO - 'XPC_SERVICE_NAME': '0', 15:41:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:57 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 15:41:57 INFO - Cleaning up... 15:41:57 INFO - Return code: 0 15:41:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:41:57 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')]} 15:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:57 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')]} 15:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41:57 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 0x10d387a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d38e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa790cea9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bd30>, '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 0x10d08fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d222b20>, '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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 15:41:57 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 15:41:57 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 15:41:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O6HX4Efzf5/Render', 15:41:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:57 INFO - 'HOME': '/Users/cltbld', 15:41:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:57 INFO - 'LOGNAME': 'cltbld', 15:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:57 INFO - 'MOZ_NO_REMOTE': '1', 15:41:57 INFO - 'NO_EM_RESTART': '1', 15:41:57 INFO - 'PAGER': '/bin/cat', 15:41:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:57 INFO - 'PWD': '/builds/slave/test', 15:41:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:57 INFO - 'SHELL': '/bin/bash', 15:41:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9VLFBjmjJL/Listeners', 15:41:57 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 15:41:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:57 INFO - 'USER': 'cltbld', 15:41:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:57 INFO - 'XPC_FLAGS': '0x0', 15:41:57 INFO - 'XPC_SERVICE_NAME': '0', 15:41:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:57 INFO - Downloading/unpacking psutil>=0.7.1 15:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:42:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:42:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:42:00 INFO - Installing collected packages: psutil 15:42:00 INFO - Running setup.py install for psutil 15:42:00 INFO - building 'psutil._psutil_osx' extension 15:42:00 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 15:42:01 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 15:42:01 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 15:42:01 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 15:42:01 INFO - building 'psutil._psutil_posix' extension 15:42:01 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 15:42:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:42:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:42:01 INFO - ^ 15:42:01 INFO - 1 warning generated. 15:42:01 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 15:42:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:42:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:42:01 INFO - Successfully installed psutil 15:42:01 INFO - Cleaning up... 15:42:02 INFO - Return code: 0 15:42:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:42: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')]} 15:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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')]} 15:42:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:02 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 0x10d387a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d38e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa790cea9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bd30>, '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 0x10d08fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d222b20>, '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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 15:42:02 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 15:42:02 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 15:42:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O6HX4Efzf5/Render', 15:42:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:02 INFO - 'HOME': '/Users/cltbld', 15:42:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:02 INFO - 'LOGNAME': 'cltbld', 15:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:02 INFO - 'MOZ_NO_REMOTE': '1', 15:42:02 INFO - 'NO_EM_RESTART': '1', 15:42:02 INFO - 'PAGER': '/bin/cat', 15:42:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:02 INFO - 'PWD': '/builds/slave/test', 15:42:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:02 INFO - 'SHELL': '/bin/bash', 15:42:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9VLFBjmjJL/Listeners', 15:42:02 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 15:42:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:02 INFO - 'USER': 'cltbld', 15:42:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:02 INFO - 'XPC_FLAGS': '0x0', 15:42:02 INFO - 'XPC_SERVICE_NAME': '0', 15:42:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:42:02 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 15:42:02 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 15:42:02 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 15:42:02 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 15:42:02 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 15:42:02 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 15:42:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:42:04 INFO - Installing collected packages: mozsystemmonitor 15:42:04 INFO - Running setup.py install for mozsystemmonitor 15:42:04 INFO - Successfully installed mozsystemmonitor 15:42:04 INFO - Cleaning up... 15:42:05 INFO - Return code: 0 15:42:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:42: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')]} 15:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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')]} 15:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:05 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 0x10d387a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d38e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa790cea9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bd30>, '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 0x10d08fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d222b20>, '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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 15:42:05 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 15:42:05 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 15:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O6HX4Efzf5/Render', 15:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:05 INFO - 'HOME': '/Users/cltbld', 15:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:05 INFO - 'LOGNAME': 'cltbld', 15:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:05 INFO - 'MOZ_NO_REMOTE': '1', 15:42:05 INFO - 'NO_EM_RESTART': '1', 15:42:05 INFO - 'PAGER': '/bin/cat', 15:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:05 INFO - 'PWD': '/builds/slave/test', 15:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:05 INFO - 'SHELL': '/bin/bash', 15:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9VLFBjmjJL/Listeners', 15:42:05 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 15:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:05 INFO - 'USER': 'cltbld', 15:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:05 INFO - 'XPC_FLAGS': '0x0', 15:42:05 INFO - 'XPC_SERVICE_NAME': '0', 15:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:05 INFO - Downloading/unpacking blobuploader==1.2.4 15:42:05 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 15:42:05 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 15:42:05 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 15:42:05 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 15:42:05 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 15:42:05 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 15:42:07 INFO - Downloading blobuploader-1.2.4.tar.gz 15:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:42:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:42:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:08 INFO - Downloading docopt-0.6.1.tar.gz 15:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:42:09 INFO - Installing collected packages: blobuploader, requests, docopt 15:42:09 INFO - Running setup.py install for blobuploader 15:42:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:42:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:42:09 INFO - Running setup.py install for requests 15:42:09 INFO - Running setup.py install for docopt 15:42:09 INFO - Successfully installed blobuploader requests docopt 15:42:09 INFO - Cleaning up... 15:42:09 INFO - Return code: 0 15:42:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:09 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 0x10d387a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d38e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa790cea9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bd30>, '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 0x10d08fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d222b20>, '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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 15:42:09 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 15:42:09 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 15:42:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O6HX4Efzf5/Render', 15:42:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:09 INFO - 'HOME': '/Users/cltbld', 15:42:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:09 INFO - 'LOGNAME': 'cltbld', 15:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:09 INFO - 'MOZ_NO_REMOTE': '1', 15:42:09 INFO - 'NO_EM_RESTART': '1', 15:42:09 INFO - 'PAGER': '/bin/cat', 15:42:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:09 INFO - 'PWD': '/builds/slave/test', 15:42:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:09 INFO - 'SHELL': '/bin/bash', 15:42:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9VLFBjmjJL/Listeners', 15:42:09 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 15:42:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:09 INFO - 'USER': 'cltbld', 15:42:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:09 INFO - 'XPC_FLAGS': '0x0', 15:42:09 INFO - 'XPC_SERVICE_NAME': '0', 15:42:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:42:10 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-EAV4LA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:42:10 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-sFd07_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:42:10 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-nMya0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:42:10 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-DK6Iym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:42:10 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-u4yax2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:42:10 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-NZwMiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:42:10 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-oj6OyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:42:10 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-xWXQGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:42:10 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-5BO11c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:42:11 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-yqtfk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:42:11 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-OG7dL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:42:11 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-qg3E7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:42:11 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-ghCMaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:42:11 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-kQ7Fpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:11 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-KXLAZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:42:11 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-sWCqri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:42:11 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-be3Tmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:42:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:42:11 INFO - Running setup.py install for manifestparser 15:42:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:42:12 INFO - Running setup.py install for mozcrash 15:42:12 INFO - Running setup.py install for mozdebug 15:42:12 INFO - Running setup.py install for mozdevice 15:42:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:42:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:42:12 INFO - Running setup.py install for mozfile 15:42:12 INFO - Running setup.py install for mozhttpd 15:42:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:42:12 INFO - Running setup.py install for mozinfo 15:42:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:42:12 INFO - Running setup.py install for mozInstall 15:42:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:42:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:42:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:42:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:42:13 INFO - Running setup.py install for mozleak 15:42:13 INFO - Running setup.py install for mozlog 15:42:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:42:13 INFO - Running setup.py install for moznetwork 15:42:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:42:13 INFO - Running setup.py install for mozprocess 15:42:13 INFO - Running setup.py install for mozprofile 15:42:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:42:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:42:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:42:13 INFO - Running setup.py install for mozrunner 15:42:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:42:14 INFO - Running setup.py install for mozscreenshot 15:42:14 INFO - Running setup.py install for moztest 15:42:14 INFO - Running setup.py install for mozversion 15:42:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:42:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:42:14 INFO - Cleaning up... 15:42:14 INFO - Return code: 0 15:42:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42: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')]} 15:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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')]} 15:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:14 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 0x10d387a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d38e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa790cea9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bd30>, '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 0x10d08fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d222b20>, '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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 15:42:14 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 15:42:14 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 15:42:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O6HX4Efzf5/Render', 15:42:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:14 INFO - 'HOME': '/Users/cltbld', 15:42:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:14 INFO - 'LOGNAME': 'cltbld', 15:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:14 INFO - 'MOZ_NO_REMOTE': '1', 15:42:14 INFO - 'NO_EM_RESTART': '1', 15:42:14 INFO - 'PAGER': '/bin/cat', 15:42:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:14 INFO - 'PWD': '/builds/slave/test', 15:42:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:14 INFO - 'SHELL': '/bin/bash', 15:42:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9VLFBjmjJL/Listeners', 15:42:14 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 15:42:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:14 INFO - 'USER': 'cltbld', 15:42:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:14 INFO - 'XPC_FLAGS': '0x0', 15:42:14 INFO - 'XPC_SERVICE_NAME': '0', 15:42:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:42:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-VWJFsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:42:14 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)) 15:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:42:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-Tlz6F_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:42:15 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)) 15:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:42:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-rLALUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:42:15 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)) 15:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:42:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-pueORO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:42:15 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)) 15:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:42:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-fgNund-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:42:15 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)) 15:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:42:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-U0plLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:42:15 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)) 15:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:42:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-ZkS4YX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:42:15 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)) 15:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:42:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-xOiasF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:42:15 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)) 15:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:42:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-F2ihhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:42:15 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)) 15:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:42:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-S6ZIKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:42:15 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)) 15:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:42:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-QchXFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:42:15 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)) 15:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:42:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-Qd3mIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:42:16 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)) 15:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:42:16 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-Cnqf8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:42:16 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)) 15:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:42:16 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-7nlFQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:42:16 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)) 15:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:16 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-kFi_4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:16 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)) 15:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:42:16 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-P_u6Ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:42:16 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)) 15:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:42:16 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-lmAPfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:42:16 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)) 15:42:16 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)) 15:42:16 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)) 15:42:16 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)) 15:42:16 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)) 15:42:16 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)) 15:42:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:18 INFO - Downloading blessings-1.6.tar.gz 15:42:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:42:19 INFO - Installing collected packages: blessings 15:42:19 INFO - Running setup.py install for blessings 15:42:19 INFO - Successfully installed blessings 15:42:19 INFO - Cleaning up... 15:42:19 INFO - Return code: 0 15:42:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:42:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:42:19 INFO - Reading from file tmpfile_stdout 15:42:19 INFO - Current package versions: 15:42:19 INFO - blessings == 1.6 15:42:19 INFO - blobuploader == 1.2.4 15:42:19 INFO - docopt == 0.6.1 15:42:19 INFO - manifestparser == 1.1 15:42:19 INFO - mozInstall == 1.12 15:42:19 INFO - mozcrash == 0.17 15:42:19 INFO - mozdebug == 0.1 15:42:19 INFO - mozdevice == 0.48 15:42:19 INFO - mozfile == 1.2 15:42:19 INFO - mozhttpd == 0.7 15:42:19 INFO - mozinfo == 0.9 15:42:19 INFO - mozleak == 0.1 15:42:19 INFO - mozlog == 3.1 15:42:19 INFO - moznetwork == 0.27 15:42:19 INFO - mozprocess == 0.22 15:42:19 INFO - mozprofile == 0.28 15:42:19 INFO - mozrunner == 6.11 15:42:19 INFO - mozscreenshot == 0.1 15:42:19 INFO - mozsystemmonitor == 0.0 15:42:19 INFO - moztest == 0.7 15:42:19 INFO - mozversion == 1.4 15:42:19 INFO - psutil == 3.1.1 15:42:19 INFO - requests == 1.2.3 15:42:19 INFO - wsgiref == 0.1.2 15:42:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42: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')]} 15:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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')]} 15:42:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:19 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 0x10d387a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d38e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa790cea9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bd30>, '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 0x10d08fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d222b20>, '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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 15:42:19 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 15:42:19 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 15:42:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O6HX4Efzf5/Render', 15:42:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:19 INFO - 'HOME': '/Users/cltbld', 15:42:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:19 INFO - 'LOGNAME': 'cltbld', 15:42:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:19 INFO - 'MOZ_NO_REMOTE': '1', 15:42:19 INFO - 'NO_EM_RESTART': '1', 15:42:19 INFO - 'PAGER': '/bin/cat', 15:42:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:19 INFO - 'PWD': '/builds/slave/test', 15:42:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:19 INFO - 'SHELL': '/bin/bash', 15:42:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9VLFBjmjJL/Listeners', 15:42:19 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 15:42:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:19 INFO - 'USER': 'cltbld', 15:42:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:19 INFO - 'XPC_FLAGS': '0x0', 15:42:19 INFO - 'XPC_SERVICE_NAME': '0', 15:42:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:19 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)) 15:42:19 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)) 15:42:19 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)) 15:42:19 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)) 15:42:19 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)) 15:42:19 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)) 15:42:19 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)) 15:42:19 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)) 15:42:19 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)) 15:42:19 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)) 15:42:19 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)) 15:42:19 INFO - Cleaning up... 15:42:19 INFO - Return code: 0 15:42:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:42: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')]} 15:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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')]} 15:42:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d387a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d38e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa790cea9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bd30>, '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 0x10d08fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d222b20>, '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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 15:42: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', 'jsonschema'] in /builds/slave/test/build 15:42: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 jsonschema 15:42:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O6HX4Efzf5/Render', 15:42:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:19 INFO - 'HOME': '/Users/cltbld', 15:42:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:19 INFO - 'LOGNAME': 'cltbld', 15:42:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:19 INFO - 'MOZ_NO_REMOTE': '1', 15:42:19 INFO - 'NO_EM_RESTART': '1', 15:42:19 INFO - 'PAGER': '/bin/cat', 15:42:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:19 INFO - 'PWD': '/builds/slave/test', 15:42:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:19 INFO - 'SHELL': '/bin/bash', 15:42:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9VLFBjmjJL/Listeners', 15:42:19 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 15:42:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:19 INFO - 'USER': 'cltbld', 15:42:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:19 INFO - 'XPC_FLAGS': '0x0', 15:42:19 INFO - 'XPC_SERVICE_NAME': '0', 15:42:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:20 INFO - Downloading/unpacking jsonschema 15:42:20 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 15:42:20 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 15:42:20 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 15:42:20 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 15:42:20 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 15:42:20 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 15:42:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:42:22 INFO - Downloading/unpacking functools32 (from jsonschema) 15:42: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 15:42: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 15:42: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 15:42: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 15:42: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 15:42: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 15:42:22 INFO - Downloading functools32-3.2.3-2.tar.gz 15:42:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:42:22 INFO - warning: no files found matching '*.txt' 15:42:22 INFO - no previously-included directories found matching 'build' 15:42:22 INFO - no previously-included directories found matching 'dist' 15:42:22 INFO - no previously-included directories found matching '.git*' 15:42:22 INFO - Installing collected packages: jsonschema, functools32 15:42:23 INFO - Running setup.py install for functools32 15:42:23 INFO - warning: no files found matching '*.txt' 15:42:23 INFO - no previously-included directories found matching 'build' 15:42:23 INFO - no previously-included directories found matching 'dist' 15:42:23 INFO - no previously-included directories found matching '.git*' 15:42:23 INFO - Successfully installed jsonschema functools32 15:42:23 INFO - Cleaning up... 15:42:23 INFO - Return code: 0 15:42:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:42:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:23 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 0x10d387a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d38e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa790cea9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bd30>, '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 0x10d08fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d222b20>, '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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 15:42:23 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 15:42:23 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 15:42:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O6HX4Efzf5/Render', 15:42:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:23 INFO - 'HOME': '/Users/cltbld', 15:42:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:23 INFO - 'LOGNAME': 'cltbld', 15:42:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:23 INFO - 'MOZ_NO_REMOTE': '1', 15:42:23 INFO - 'NO_EM_RESTART': '1', 15:42:23 INFO - 'PAGER': '/bin/cat', 15:42:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:23 INFO - 'PWD': '/builds/slave/test', 15:42:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:23 INFO - 'SHELL': '/bin/bash', 15:42:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9VLFBjmjJL/Listeners', 15:42:23 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 15:42:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:23 INFO - 'USER': 'cltbld', 15:42:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:23 INFO - 'XPC_FLAGS': '0x0', 15:42:23 INFO - 'XPC_SERVICE_NAME': '0', 15:42:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:23 INFO - Downloading/unpacking flake8 15:42:23 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 15:42:23 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 15:42:23 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 15:42:23 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 15:42:23 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 15:42:23 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 15:42:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:42:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:42: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 15:42: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 15:42: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 15:42: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 15:42: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 15:42: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 15:42:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:42:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:42:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:42:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:42:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:42:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:42:27 INFO - no previously-included directories found matching 'docs/_build' 15:42:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:42:27 INFO - Running setup.py install for pep8 15:42:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:42:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:42:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:42:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:42:27 INFO - no previously-included directories found matching 'docs/_build' 15:42:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:42:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:42:27 INFO - Cleaning up... 15:42:27 INFO - Return code: 0 15:42:27 INFO - Running post-action listener: _resource_record_post_action 15:42:27 INFO - Running post-action listener: _start_resource_monitoring 15:42:27 INFO - Starting resource monitoring. 15:42:27 INFO - ##### 15:42:27 INFO - ##### Running install step. 15:42:27 INFO - ##### 15:42:27 INFO - Running pre-action listener: _resource_record_pre_action 15:42:27 INFO - Running main action method: install 15:42:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:42:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:42:27 INFO - Reading from file tmpfile_stdout 15:42:27 INFO - Detecting whether we're running mozinstall >=1.0... 15:42:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:42:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:42:28 INFO - Reading from file tmpfile_stdout 15:42:28 INFO - Output received: 15:42:28 INFO - Usage: mozinstall [options] installer 15:42:28 INFO - Options: 15:42:28 INFO - -h, --help show this help message and exit 15:42:28 INFO - -d DEST, --destination=DEST 15:42:28 INFO - Directory to install application into. [default: 15:42:28 INFO - "/builds/slave/test"] 15:42:28 INFO - --app=APP Application being installed. [default: firefox] 15:42:28 INFO - mkdir: /builds/slave/test/build/application 15:42:28 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'] 15:42:28 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 15:42:50 INFO - Reading from file tmpfile_stdout 15:42:50 INFO - Output received: 15:42:50 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:42:50 INFO - Running post-action listener: _resource_record_post_action 15:42:50 INFO - ##### 15:42:50 INFO - ##### Running run-tests step. 15:42:50 INFO - ##### 15:42:50 INFO - Running pre-action listener: _resource_record_pre_action 15:42:50 INFO - Running main action method: run_tests 15:42:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:42:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:42:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:42:50 INFO - 2016-05-09 15:42:50.848 screenresolution[1644:7478] starting screenresolution argv=screenresolution get 15:42:50 INFO - 2016-05-09 15:42:50.851 screenresolution[1644:7478] Display 0: 1600x1200x32@60 15:42:50 INFO - 2016-05-09 15:42:50.856 screenresolution[1645:7480] starting screenresolution argv=screenresolution list 15:42:50 INFO - Available Modes on Display 0 15:42:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:42:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:42:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:42:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:42:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:42:51 INFO - Intel Iris: 15:42:51 INFO - Chipset Model: Intel Iris 15:42:51 INFO - Type: GPU 15:42:51 INFO - Bus: Built-In 15:42:51 INFO - VRAM (Dynamic, Max): 1536 MB 15:42:51 INFO - Vendor: Intel (0x8086) 15:42:51 INFO - Device ID: 0x0a2e 15:42:51 INFO - Revision ID: 0x0009 15:42:51 INFO - Displays: 15:42:51 INFO - Display: 15:42:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:42:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:42:51 INFO - Display Serial Number: 1600x1200 60 15:42:51 INFO - Main Display: Yes 15:42:51 INFO - Mirror: Off 15:42:51 INFO - Online: Yes 15:42:51 INFO - Rotation: Supported 15:42:51 INFO - Return code: 0 15:42:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:42:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:42:51 INFO - Python 2.7.3 15:42:51 INFO - Return code: 0 15:42:51 INFO - grabbing minidump binary from tooltool 15:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:51 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 0x10d22bd30>, '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 0x10d08fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d222b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:42:51 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 15:42:51 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 15:42:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:42:51 INFO - Return code: 0 15:42:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:42:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:42:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:42:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:42:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:42:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:42:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:42:52 INFO - Return code: 0 15:42:52 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-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:42:52 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-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:42:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.O6HX4Efzf5/Render', 15:42:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:52 INFO - 'HOME': '/Users/cltbld', 15:42:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:52 INFO - 'LOGNAME': 'cltbld', 15:42:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:42:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:52 INFO - 'MOZ_NO_REMOTE': '1', 15:42:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:42:52 INFO - 'NO_EM_RESTART': '1', 15:42:52 INFO - 'PAGER': '/bin/cat', 15:42:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:52 INFO - 'PWD': '/builds/slave/test', 15:42:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:42:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:52 INFO - 'SHELL': '/bin/bash', 15:42:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9VLFBjmjJL/Listeners', 15:42:52 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 15:42:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:52 INFO - 'USER': 'cltbld', 15:42:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:52 INFO - 'XPC_FLAGS': '0x0', 15:42:52 INFO - 'XPC_SERVICE_NAME': '0', 15:42:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:42:52 INFO - mozversion application_buildid: 20160509134609 15:42:52 INFO - mozversion application_changeset: 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 15:42:52 INFO - mozversion application_display_name: Firefox 15:42:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:42:52 INFO - mozversion application_name: Firefox 15:42:52 INFO - mozversion application_remotingname: firefox 15:42:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:42:52 INFO - mozversion application_vendor: Mozilla 15:42:52 INFO - mozversion application_version: 47.0 15:42:52 INFO - mozversion platform_buildid: 20160509134609 15:42:52 INFO - mozversion platform_changeset: 2b710898eaf609aa9dc45fd75e03bc134d20f6b8 15:42:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:42:52 INFO - mozversion platform_version: 47.0 15:42:52 INFO - using testdate: 1462833772 15:42:52 INFO - actual date: 1462833772 15:42:52 INFO - starting webserver on 'localhost:49278' 15:42:52 INFO - SUITE-START | Running 2 tests 15:42:52 INFO - TEST-START | dromaeo_css 15:42:52 INFO - operating with platform_type : mac_ 15:42:52 INFO - Initialising browser for dromaeo_css test... 15:42:52 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpskn8be/profile http://localhost:49278/getInfo.html) 15:42:55 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 15:42:55 INFO - PROCESS | 1663 | colorDepth:24 15:42:55 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 15:42:55 INFO - PROCESS | 1663 | __metrics 15:42:55 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 15:42:55 INFO - TEST-INFO | 1663: exit 0 15:42:55 INFO - Browser initialized. 15:42:55 INFO - Running cycle 1/1 for dromaeo_css test... 15:42:55 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpskn8be/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:43:07 INFO - PROCESS | 1667 | RSS: Main: 168189952 15:43:07 INFO - PROCESS | 1667 | 15:43:57 INFO - PROCESS | 1667 | 1462833837412 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] 15:46:07 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 15:46:08 INFO - PROCESS | 1667 | RSS: Main: 178659328 15:46:08 INFO - PROCESS | 1667 | 15:49:10 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 15:49:11 INFO - PROCESS | 1667 | RSS: Main: 190099456 15:49:11 INFO - PROCESS | 1667 | 15:52:12 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 15:52:13 INFO - PROCESS | 1667 | RSS: Main: 192098304 15:52:13 INFO - PROCESS | 1667 | 15:55:14 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 15:55:15 INFO - PROCESS | 1667 | RSS: Main: 195465216 15:55:15 INFO - PROCESS | 1667 | 15:58:18 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 15:58:18 INFO - PROCESS | 1667 | RSS: Main: 194727936 15:58:18 INFO - PROCESS | 1667 | 16:01:20 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 16:01:21 INFO - PROCESS | 1667 | RSS: Main: 200302592 16:01:21 INFO - PROCESS | 1667 | 16:01:21 INFO - PROCESS | 1667 | __start_tp_report 16:01:21 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 16:01:21 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 16:01:21 INFO - PROCESS | 1667 | |i|pagename|runs| 16:01:21 INFO - PROCESS | 1667 | |0;dojo.html;3625.3111908737337;3639.836207370668;3647.3981793004154;3700.0749812546874;3701.148735790768;5718.7426565804535;5807.970960145204;5808.796692115766;5870.295564532256;5873.2658417697785;5794.565407594426;5801.274840644919;5839.182514447981;5844.26946631671;5850.4734573888345;5234.92147617786;5273.525382715555;5332.226827110067;5346.1713434417725;5368.32895888014;5256.8160114396005;5315.813946511854;5359.5712343012465;5368.436314187013;5393.919091213635;5320.813771517997;5349.545288650469;5402.108652072403;5422.15956526739;5430.104032834597;5385.380681221671;5390.73046347683;5390.97304513475;5404.972975135119;5408.756605952742;5127.2052831811125;5225.790968361261;5249.4488078751565;5309.681419114865;5384.838454846361;5555.2500412444615;5658.830235092953;5689;5713.285839270091;5718.25662663851;6194.25668919728;6244.500439964837;6273.184486016834;6276.309605943343;6278.089676996856;6150.1389805426825;6153.353897840707;6197.721102550396;6218.906716399258;6250.9062364064575;5396.947595218934;5564.749586268627;5956.702164891738;5979.983402821531;5994.730237139339;6347.428731414103;6353.14232578596;6361.936380636227;6376.075469057054;6419.743210271537;6134.00801433197;6168.722407491674;6200.937990620127;6258.3428739982555;6287.779927702509;6231.470325022333;6240.00159974411;6245.906311405332;6260.280067792262;6266.248050234001;6121.020636698196;6199.411055949678;6248.812535623939;6271.341509141565;6272.5922815016875;4846.5153484651255;5046.59627229828;5495.395506494282;6190.3190649028575;6206.348333425016;4934.259861020804;5021.698698078127;5038.79844806211;5047.369078865142;5090.872728181751;4414;4422.535633758409;4538.931916021263;4543.840965566189;4552.362669226311;4800.279958006327;5706.429357064343;5713.085906255063;5787.276590426197;5872.677002764804;6196.628202307876;6198.101275315144;6210.161628180138;6222.12890195373;6222.315545290015;5726.284214473191;5838.562107841929;5906.055031195074;5933;5948.405159484017;5444.047291723932;5472.671639701631;5501.559875209973;5514.834554963358;5571.860703482445;6441.066045423388;6464.353564643498;6466.094746735461;6492.026196070533;6500.707468163944;6159.938400616115;6187.969060154671;6211.689415529115;6234.532410069172;6241.563090583615;6402.167718196604;6410.679466026587;6438.163038804925;6439.67801609927;6446.161998940108;5123.359580052494;6271.996480563088;6281.842953926189;6295.622262664255;6308.558400911892;6149.631022138686;6176.320604733386;6199.907001394892;6224.875502489835;6254;5160;5576.386597474356;6141.385861413823;6212.844678882883;6234.657093859882;5044.470330615232;5755.884882302246;6165.383461653799;6208.72060757258;6215.96892015537;5735.397783232867;5888.293404791452;5929.495992840657;6040.909386359296;6094.969525152346;4508.774561271989;6103.542234332532;6143.324234334309;6172.753089876354;6187.659678717714;5935.169076329231;6039.48664363534;6086.02623580225;6108.969455152874;6119.388061193845 16:01:21 INFO - PROCESS | 1667 | |1;ext.html;476.3783262841992;617.9772476551309;623.3019018699055;677.1907570453309;678.6131904814256;40151.79924100379;40752.18495630086;40759.388609170885;40799.592004079954;40957;42250;42312.57687423125;42327.365089523606;42436;42949.57050429495;9673.661421850255;9891.554880030415;9894.50527473627;9950.154236889874;10006.249531285146;9813.607455701798;10013.649522266722;10084.14284785794;10095.596176152945;10122.89877101227;8542.316614670843;10033.548490317953;10120.746981325488;10122.190224782;10140.391576505433;9093.727188184299;9529.856417229978;10008.049235322633;10110.494475276244;10123;9961.950190249003;10003;10092.192624590089;10095.848562271498;10125.29122961393;29821.850890745623;35478.29043419143;37725.62274377249;41037.589624103675;41831.37252941208;3223.4842425212087;3328.983355083233;3399.8640054397792;3500.7549471537004;3512.244867353506;26101.869490652614;28142.437151257065;28147.718522814714;28934;29565.113046608636;2327.3483424641126;2791.7347851954237;2809.4100238949845;2873.6551613806478;3012.5932999045285;336561;336991;337823;337826;337906;48844.267335989985;49361.012779744924;49364;70023;71333.57332853314;39547.40678889819;47411.5258847414;47757;47833.52166478291;48060.27909581359;16934.661306774044;17090.48728538146;17259;17373.47879563615;17404.303827846998;13419.261940593362;13441.193528388329;13495;13564.186148830904;13648.93175534116;15699.058056516646;15818.60453488649;15832.525024249291;15857.286422110801;15898.284577194054;5575.470330318613;6518.511111666644;6590.8352291193105;6631.3700198481065;6669.498513280567;1030.7474668706066;1032.396048311746;1034.3018462537757;1172.355204637446;1182.4619797992066;29974;30160;31057.0682879514;31191;31972.200694982348;1066.3015724700333;1085.4898197846999;1087.0434018064211;1154.8440960470398;1158.80879654856;15158.924205379122;15225.695486090217;15602.609934751723;15629.765553516707;15973.361065557248;449955;485414;539861;548368;548887;61930;61964.76070478565;63764.68117659475;63778;63802.68098659477;1186.976260474803;1530.188999830092;1532.8926975111858;1541.5837723814507;1590.9204539772966;28425.715742842305;28599;28871.85564072166;30433.69566304397;30924.381512370077;55774.163387548404;56129.71935140298;56293;56837;57098.42901570931;425487;426418;438398;438943;439262.8036859795;35060.474092888115;35986.64013359833;36059.27881442409;36353.09117271983;36564.63435365719;47847.760761197365;47894;48145.518544814106;49059.264111039076;49370.39676882853;39428.80285598554;39821.80089099536;40130;40145.59854401419;40327.1934561313;39022.4146637795;39468.81593552198;39547.40678889876;39764.80117599509;40012.1997560053;39940.800295998335;40220.1955960885;40288;40883.18233635251;42007.1598568033;31504.684953150176;33196.004119876445;33293.16767080843;33300.333993320484;33304.666953330154;25464.981400743764;25473.28922287759;25692.229233123035;25874.870625646752;26038.86980565085 16:01:21 INFO - PROCESS | 1667 | |2;jquery.html;337.85134540802045;353.1947160474119;377.58970246130696;382.0620376974527;388.98833035008926;20034;20243.392698219057;20495.487612809688;21119.472013199676;21138.788612113873;16331.755023674654;20371.28700495483;20445.48886277844;20971;21458.89270553645;5367.946320536803;5645.096784514479;5937.970310148443;6023.668698221596;6112.602680825732;5806.535477161838;5892.911606325909;5924.703764811764;5956.583039187237;6086.360932102132;5411.783528658849;5705.343885453164;5899.085641725518;5998.8800223995495;6000.489958353545;6061.242344706911;6138.754449822001;6149.938500614981;6167.87664246717;6181.567290289681;5862.296524417097;5895.616784908967;5943.286805583358;5955.851103722398;5985.281766188085;13718.314084295846;14101.64745881351;14122.71754564903;14186;18318.450446486626;2261.9886900565557;2266.614675505168;2307.2962746362355;2322.558713844381;2358.8230882683865;10030.749231269203;10380.532876020598;10467.005634464713;10534.26260161789;13101.41043653038;1788.7316902464734;1805.1154934082335;1812.474382429094;1840.6594779965615;1875.2499000399812;102061.48969255181;103081;104685.42971855428;110390.44804776004;110776.4461177689;11438.370889601152;11490.655280341603;11684.88315116855;11826.231294965799;11934;14477.203753793643;14610.63473413152;14620.195889225983;14629;14654.926725366306;8248.257656810916;8382.036065852382;9032.45805251687;9195;9229.676961306322;9401.153896149379;9421.62313507452;9447.433154010781;9453.716388508356;9536.28477864163;3828.578856325802;4544;4620.745858977789;4637.5130611286;4665.626749860003;1936.5061909213061;1986.106252186522;1992.2529051605648;2011.8088781565727;2015.9294424695065;803.8392321535716;1047.528612124547;1093.868735751715;1097.1442275025493;1097.8902109789117;20946.89526552388;21673.89163054206;21708.240211592518;21798.877263842984;22202.888985554968;956.9569369378395;957.711877524742;1001.9549120289604;1052.4790228836775;1054.6361505280665;11332.263402878787;11769.88230117705;11773.058155347551;11781.76436471283;11843;684538.9447612524;693026;699313;720219;738047;100076;116861;123580.38209809073;131178;135707;1020.9285350025575;1025.195221750922;1026.7616595821182;1049.5854137615752;1052.7894421115807;19067.80932190716;19218.615627687646;19316.420507384802;19354.903225483784;19419.4174174775;51599;52349.42951711455;52722.472775273294;52763;53145.202821956926;73991.26008739843;74863.25136748781;78094;78816.60591697005;80410.79383809364;8291.668333266602;8332.125126861834;8349.95825020871;8359.623816928131;8405.873911891204;9681.305771490102;12957.740845183233;13592.048556600943;13653.726925461633;13941;11498.94250528742;11724.58963936258;11974.58088966882;12011.399430028288;12131.696707582381;11530.365829879438;11679.649610511698;11743.41282935867;11854.466549005143;12039;11986;11998.280103193836;12030.458629361874;12123.75752484963;12126.393680316125;8384.748457546284;8402.537860417491;8436.704715334934;8540.786480337792;8818.603162857815;11901.464434100659;12096.637100886988;12133.575324863587;12187.573434929735;12221.877781222074 16:01:21 INFO - PROCESS | 1667 | |3;mootools.html;1515.57563882113;1629.6251862071724;1654.5367297156797;2067.10081114715;2078.1168003598477;7978.84042319154;8358.665653373857;9210.263178945685;9233.722988310354;9603.039696030402;7736.1877002914725;9213.401128926615;9284.90715092849;9384.061593840612;9405.388649737763;2522.7351128131513;2525.6337831014584;2536.809739269553;2549.120553409071;2549.872506374674;2569.717331093578;2608.67391576053;2635.9604605930926;2636.986815065922;2643.510950474159;2588.3011586871535;2591.1967290140024;2608.67391576053;2616.7906567474556;2619.3058839407477;2592.313037045194;2613.73862613739;2628.158989123481;2631.776299014586;2633.5654616988268;2596.883140258693;2596.9220923372327;2616.856072915989;2628.0670362021397;2644.074573899139;9349.345545811795;9459.952700236523;9466.479343636098;9492.288078394147;9505.239580833517;5833.445822646841;5944;5979.760809567612;6032.155498230251;6050.576459647827;3651.050726811022;3706.721995850322;3831.3869780834934;3837.0599203195115;3838.75048121874;1080.4327727942814;1089.2375337263948;1090.2640717515644;1130.9547618095266;1133.2463911498844;12670.873291267062;12707.428165732565;12737.93631031839;12743.936280318632;12791.680207994781;5106.412762532282;5137.717425541631;5214.947850521522;5235.214717792362;5304.469553044664;10900.182486313559;10926.344419334866;10935.343879367105;10945.83581246282;10973;4955.975220123948;5750.88498230033;5946.256717910262;5973.611715238496;5985;4276.8289268429535;4748.145333839942;5123.718195499208;5254.579633629301;5328.37484567203;3518.5425894633536;4116.835326586963;4154.626083652486;4226.450561426995;4260.723053001545;1382.3917476310392;1382.7372799168127;1395.720855828818;1399.1465206224195;1399.15351212517;1379.6412932637386;1413.5052731543876;1433.7562614355584;1448.5292280008828;1470.477980316972;8474.661013559511;8584.227419532272;8664.523451210243;8726.476411415335;8805.779855503664;507.67508794371633;547.7507733981029;552.8728392469754;553.6871667507845;559.846042338352;6470.288268290485;6517.706703198368;6533.150690410216;6544.083828264047;6604.537682362188;9800.754981125529;10534.841977370346;11337.696164940973;11550;11655.067594592412;10880;11274.774504509864;11409.25839820409;11452.198346115692;11582.6525204244;8567.914320856711;8601.31189504838;8646.265067469336;8741.562921853882;8882.955585222033;8452.450590711584;8524.531150786519;8588;8644.567771611268;8685.392022558613;10055.346402483816;10095.141912937483;10166.847497287694;10170.288079834341;10237.488125593542;10083.848742268725;10409.998551947618;10624.36253824773;10677.359358438518;10787.568497260021;7240.348368646847;7275.090613673291;7773.261540153676;7976.441649084509;8143.42995990275;8139.877901831359;8790.868136977822;8862.911370886466;8937.150970657776;9092.227160691415;7598.316151546387;7782.805429864298;7798.610069496389;7818.491798033109;7910.169432209535;6336.398042185985;7649.005629268159;7655.57894315818;7691.1539730628465;7798.37612990948;8242.876356854533;8330.166983301622;8352.248297653241;8387.748367548984;8388.119247478928;7253.746118886025;7417.851642967003;7457.440691948234;7504.437167212372;7544.170141284345;6424.871502570016;7549.7357592483995;7563.848723025619;7605.581693006938;7615.200403957726 16:01:21 INFO - PROCESS | 1667 | |4;prototype.html;339.21302578019;339.4043453738688;344.5073544830894;346.5564077980186;347.1304382521781;6869.278725733801;6895.896561551581;6925.480588955823;6955.965220173892;6957.408620267277;6889.931100688992;6923.446124310056;6924.446044316455;6938.826529336769;6958.756443524478;4186.853460128896;4884.267359896026;5002.574781143607;5072.289879416885;5099.490050994889;5055.5702765265;5073.3404657394485;5094.46508116648;5109.489051094898;5110.488951104897;5032.345795046657;5040.924386134211;5051.0150520648485;5059.16523773577;5066.392032956032;5028.647994640377;5029.3713285839085;5036.395632524121;5039.672421292623;5047.0915235257635;4396.944733263994;4405.383246345515;4410.42664453619;4421.513633500313;4422.425084738963;6537.803865884031;6599.142111525519;6732.528722989393;6732.764353247662;6782.966085169592;5977;6023.819285421444;6025.638461692269;6027.547933904974;6043.546733994924;6004.5796794224425;7427.405807535438;7459.515131516434;7461.589612571306;7480.513766605153;4909.239067944439;4946.505349465025;4947.50524947506;4967.900641987176;4972.776225069844;7056.858862822714;7256.624032062584;7468.9626551866895;7907.683692652287;7935.761927142137;4781.617470602343;4895.485973972753;4975.5522003019905;4990;5003.824866129668;4158.792060396968;5064.215046667772;5069.315642388304;5074.619403544749;5107.131787596118;5911.261092363455;5985.191999080069;5989.431004054608;6005.3394126646035;6005.579609427386;7441.8139546511775;7471.738489152854;7482.625868706543;7494.587797671072;7504.849903001909;1732.6015016546264;1751.5653241993639;1773.7782777152856;1801.1354549816162;1808.2947650416347;972.9756756081155;975.6000039983714;1032.6282538286212;1109.4286442482128;1153.7519433321709;7039.472039597051;7040.894386584206;7077.681504332318;7092.184398794101;7108.040414544073;6362.586431881913;7635.121960974448;7732.9226707732205;7738.380929525624;7759.340456061298;7041.507094503438;7078;7095.396891264197;7096.148462184571;7108.040414544073;3940.960590394059;4043.757374557536;4086.7752273625238;4086.9795651021554;4087.346024636044;8074.555899425588;8123.18768123183;8161.918380816116;8256.752297431087;8257.876131858027;8075.717349892726;8091.838163236703;8093.919060809317;8098.919010809816;8110.797230069295;8208.630611622371;8209.38429617793;8211.260986511243;8222.465539740017;8227.588620568948;6264.68676566179;7517.849643007218;7812.492188007761;7839.33365663925;7844.254795794391;7660.131633525034;7686.154523002353;7687.5387476751575;7687.5387476751575;7691.230876912264;6613.305602911625;8148.429609927248;8155.755327340189;8169.673213071411;8183.345332373515;4899.608031357554;5035.345405097314;5105.948940510547;5115.079285728604;5127.025865085622;6207.906881396692;7502.774916752506;7678.654460549177;7686.730964416219;7723.7682869514;7387.704491820267;7424.480286379901;7489.737859174903;7516.924830751841;7524.774256772305;7450.92549074524;7480.850382992201;7497.2877576630135;7517.436192285491;7525;7044.260352662896;7322.414206863332;7334.3032411920785;7363.558186508612;7370.262973702587;6616.470682345304;6998;7469.88795168062;7515.586642734484;7523.774286771406;5850.882982340415;6544.9672751635935;6748.751480976035;7318.1949985502615;7319.4144468443465 16:01:21 INFO - PROCESS | 1667 | |5;yui.html;691.4433880726014;702.2696395748421;733.3949491669371;753.6495529578744;779.6491578789543;13479.528216512424;13620.250886201267;13679.452821887113;13704.794428083562;13815.861841381584;14531.346089425975;16411.67176656469;16751.911125776845;16782.916085419554;16820.66358672829;798.3453568074182;960.5341409416445;968.9563969621348;973.9026097390275;979.5298256836722;985.4924713772413;988.2884323287221;991.3605724307818;992.7617371830781;994.3636072913341;1014.5688082564917;1046.2519298701427;1049.4857519815273;1049.8635177426925;1050.6637875879721;1021.3616489693941;1038.9636362727344;1044.0864243786687;1044.1803184500206;1046.5604446132643;766.4634755671052;776.0919723923024;776.5185584937318;778.5912395992093;780.3406121827047;15465.149416782073;15558.9222053889;15747.68504629901;15846.603834904105;15861.841381586151;12967.286799226133;13058.477660893554;13144.079914405995;13157.53948611794;13243.205407675474;10281;10472;10490.685279441628;10499.94750026245;10567.471626418648;3431.1250631088915;3562.8931132066077;3582.0507565495163;3622.0582648511313;3673.687736542397;66482;85202;85412;86462.13537864604;87366.56316718376;9577.377470464398;9744.07431294026;9795.118439340493;9930.106290433896;9955.950220248853;21531.246406375703;21748.02133903978;22001.779982200293;22106.778932210793;22108.00513976875;7460.813479663052;7543.47195696296;7616.314531692175;7620.847583048308;7641.35048520882;4171.478565179353;4195.832166713359;4212.262854000538;4224.894377640584;4296.2696341621995;3699.389600715852;3741.326561218952;3741.3639681254253;3743.0642339415144;3775.112848480605;578.7077525849419;665.3679004945321;671.0739179931663;675.7533500272365;679.823245956055;574.9995008684941;576.2854060964457;607.6080927801544;612.2805703298695;622.9439350458481;8325.18937796718;8431.915680843236;8437.493750374875;8465;8512.029483007114;541.2557733116964;581.514435446407;611.4649681528663;613.8894998900241;624.9562530622812;3419.7777144486026;3733.3653278942643;3891;3994.7003974702016;4111.753294802322;74445.62777186079;91069;96467.51766241265;97106;98386.5080674592;78945;79100.60449697715;80431.59784201042;80464.59767701125;80505;72455.6377218121;75169.62415187889;75565;75804.62097689587;76271.23728762641;7557.206493318123;8614.569271536273;8626.91373086261;8846.424982376126;8989.280857531512;40179;51485.48514514957;53233;53310.200346994054;54002.459975399746;57465;70401.29598703947;73376;75538;75749.45503269821;11127.221094523306;11228.494717737558;11320.773584528428;11390.145739069769;11758.882411175779;12181.939090304491;12326.938365308117;12496.437660305126;12590.685232869251;12700;11566.884331156582;11621.941890290494;11740.471678774305;11754.70613234676;12107.878921210675;11376.829347559627;11843.822342665038;11883.76232475363;11963.401829908644;12011.579594714492;9529.952350238482;10547.208959328234;10738.785224295314;11580.826287605862;12163.391830408622;8405.95797021011;8582.485050896967;9313.906860931304;9953.15398191133;10079.395236285847;9445;9851.261155413229;9893.159081478156;9898.604055837974;9967.10296073357 16:01:21 INFO - PROCESS | 1667 | __end_tp_report 16:01:21 INFO - PROCESS | 1667 | __start_cc_report 16:01:21 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,358 16:01:21 INFO - PROCESS | 1667 | __end_cc_report 16:01:21 INFO - PROCESS | 1667 | __startTimestamp1462834881044__endTimestamp 16:01:21 INFO - PROCESS | 1667 | 16:01:21 INFO - PROCESS | 1667 | ------- Summary: start ------- 16:01:21 INFO - PROCESS | 1667 | Number of tests: 6 16:01:21 INFO - PROCESS | 1667 | 16:01:21 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5760.69 Median:5933.00 stddev:617.38 (10.4%) stddev-sans-first:596.11 16:01:21 INFO - PROCESS | 1667 | Values: 3625.3 3639.8 3647.4 3700.1 3701.1 5718.7 5808.0 5808.8 5870.3 5873.3 5794.6 5801.3 5839.2 5844.3 5850.5 5234.9 5273.5 5332.2 5346.2 5368.3 5256.8 5315.8 5359.6 5368.4 5393.9 5320.8 5349.5 5402.1 5422.2 5430.1 5385.4 5390.7 5391.0 5405.0 5408.8 5127.2 5225.8 5249.4 5309.7 5384.8 5555.3 5658.8 5689.0 5713.3 5718.3 6194.3 6244.5 6273.2 6276.3 6278.1 6150.1 6153.4 6197.7 6218.9 6250.9 5396.9 5564.7 5956.7 5980.0 5994.7 6347.4 6353.1 6361.9 6376.1 6419.7 6134.0 6168.7 6200.9 6258.3 6287.8 6231.5 6240.0 6245.9 6260.3 6266.2 6121.0 6199.4 6248.8 6271.3 6272.6 4846.5 5046.6 5495.4 6190.3 6206.3 4934.3 5021.7 5038.8 5047.4 5090.9 4414.0 4422.5 4538.9 4543.8 4552.4 4800.3 5706.4 5713.1 5787.3 5872.7 6196.6 6198.1 6210.2 6222.1 6222.3 5726.3 5838.6 5906.1 5933.0 5948.4 5444.0 5472.7 5501.6 5514.8 5571.9 6441.1 6464.4 6466.1 6492.0 6500.7 6159.9 6188.0 6211.7 6234.5 6241.6 6402.2 6410.7 6438.2 6439.7 6446.2 5123.4 6272.0 6281.8 6295.6 6308.6 6149.6 6176.3 6199.9 6224.9 6254.0 5160.0 5576.4 6141.4 6212.8 6234.7 5044.5 5755.9 6165.4 6208.7 6216.0 5735.4 5888.3 5929.5 6040.9 6095.0 4508.8 6103.5 6143.3 6172.8 6187.7 5935.2 6039.5 6086.0 6109.0 6119.4 16:01:21 INFO - PROCESS | 1667 | 16:01:21 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:58598.85 Median:19280.61 stddev:115385.79 (598.5%) stddev-sans-first:115626.95 16:01:21 INFO - PROCESS | 1667 | Values: 476.4 618.0 623.3 677.2 678.6 40151.8 40752.2 40759.4 40799.6 40957.0 42250.0 42312.6 42327.4 42436.0 42949.6 9673.7 9891.6 9894.5 9950.2 10006.2 9813.6 10013.6 10084.1 10095.6 10122.9 8542.3 10033.5 10120.7 10122.2 10140.4 9093.7 9529.9 10008.0 10110.5 10123.0 9962.0 10003.0 10092.2 10095.8 10125.3 29821.9 35478.3 37725.6 41037.6 41831.4 3223.5 3329.0 3399.9 3500.8 3512.2 26101.9 28142.4 28147.7 28934.0 29565.1 2327.3 2791.7 2809.4 2873.7 3012.6 336561.0 336991.0 337823.0 337826.0 337906.0 48844.3 49361.0 49364.0 70023.0 71333.6 39547.4 47411.5 47757.0 47833.5 48060.3 16934.7 17090.5 17259.0 17373.5 17404.3 13419.3 13441.2 13495.0 13564.2 13648.9 15699.1 15818.6 15832.5 15857.3 15898.3 5575.5 6518.5 6590.8 6631.4 6669.5 1030.7 1032.4 1034.3 1172.4 1182.5 29974.0 30160.0 31057.1 31191.0 31972.2 1066.3 1085.5 1087.0 1154.8 1158.8 15158.9 15225.7 15602.6 15629.8 15973.4 449955.0 485414.0 539861.0 548368.0 548887.0 61930.0 61964.8 63764.7 63778.0 63802.7 1187.0 1530.2 1532.9 1541.6 1590.9 28425.7 28599.0 28871.9 30433.7 30924.4 55774.2 56129.7 56293.0 56837.0 57098.4 425487.0 426418.0 438398.0 438943.0 439262.8 35060.5 35986.6 36059.3 36353.1 36564.6 47847.8 47894.0 48145.5 49059.3 49370.4 39428.8 39821.8 40130.0 40145.6 40327.2 39022.4 39468.8 39547.4 39764.8 40012.2 39940.8 40220.2 40288.0 40883.2 42007.2 31504.7 33196.0 33293.2 33300.3 33304.7 25465.0 25473.3 25692.2 25874.9 26038.9 16:01:21 INFO - PROCESS | 1667 | 16:01:21 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:37472.78 Median:20470.49 stddev:116801.81 (570.6%) stddev-sans-first:117096.19 16:01:21 INFO - PROCESS | 1667 | Values: 337.9 353.2 377.6 382.1 389.0 20034.0 20243.4 20495.5 21119.5 21138.8 16331.8 20371.3 20445.5 20971.0 21458.9 5367.9 5645.1 5938.0 6023.7 6112.6 5806.5 5892.9 5924.7 5956.6 6086.4 5411.8 5705.3 5899.1 5998.9 6000.5 6061.2 6138.8 6149.9 6167.9 6181.6 5862.3 5895.6 5943.3 5955.9 5985.3 13718.3 14101.6 14122.7 14186.0 18318.5 2262.0 2266.6 2307.3 2322.6 2358.8 10030.7 10380.5 10467.0 10534.3 13101.4 1788.7 1805.1 1812.5 1840.7 1875.2 102061.5 103081.0 104685.4 110390.4 110776.4 11438.4 11490.7 11684.9 11826.2 11934.0 14477.2 14610.6 14620.2 14629.0 14654.9 8248.3 8382.0 9032.5 9195.0 9229.7 9401.2 9421.6 9447.4 9453.7 9536.3 3828.6 4544.0 4620.7 4637.5 4665.6 1936.5 1986.1 1992.3 2011.8 2015.9 803.8 1047.5 1093.9 1097.1 1097.9 20946.9 21673.9 21708.2 21798.9 22202.9 957.0 957.7 1002.0 1052.5 1054.6 11332.3 11769.9 11773.1 11781.8 11843.0 684538.9 693026.0 699313.0 720219.0 738047.0 100076.0 116861.0 123580.4 131178.0 135707.0 1020.9 1025.2 1026.8 1049.6 1052.8 19067.8 19218.6 19316.4 19354.9 19419.4 51599.0 52349.4 52722.5 52763.0 53145.2 73991.3 74863.3 78094.0 78816.6 80410.8 8291.7 8332.1 8350.0 8359.6 8405.9 9681.3 12957.7 13592.0 13653.7 13941.0 11498.9 11724.6 11974.6 12011.4 12131.7 11530.4 11679.6 11743.4 11854.5 12039.0 11986.0 11998.3 12030.5 12123.8 12126.4 8384.7 8402.5 8436.7 8540.8 8818.6 11901.5 12096.6 12133.6 12187.6 12221.9 16:01:21 INFO - PROCESS | 1667 | 16:01:21 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:6392.08 Median:5176.33 stddev:3404.31 (65.8%) stddev-sans-first:3394.13 16:01:21 INFO - PROCESS | 1667 | Values: 1515.6 1629.6 1654.5 2067.1 2078.1 7978.8 8358.7 9210.3 9233.7 9603.0 7736.2 9213.4 9284.9 9384.1 9405.4 2522.7 2525.6 2536.8 2549.1 2549.9 2569.7 2608.7 2636.0 2637.0 2643.5 2588.3 2591.2 2608.7 2616.8 2619.3 2592.3 2613.7 2628.2 2631.8 2633.6 2596.9 2596.9 2616.9 2628.1 2644.1 9349.3 9460.0 9466.5 9492.3 9505.2 5833.4 5944.0 5979.8 6032.2 6050.6 3651.1 3706.7 3831.4 3837.1 3838.8 1080.4 1089.2 1090.3 1131.0 1133.2 12670.9 12707.4 12737.9 12743.9 12791.7 5106.4 5137.7 5214.9 5235.2 5304.5 10900.2 10926.3 10935.3 10945.8 10973.0 4956.0 5750.9 5946.3 5973.6 5985.0 4276.8 4748.1 5123.7 5254.6 5328.4 3518.5 4116.8 4154.6 4226.5 4260.7 1382.4 1382.7 1395.7 1399.1 1399.2 1379.6 1413.5 1433.8 1448.5 1470.5 8474.7 8584.2 8664.5 8726.5 8805.8 507.7 547.8 552.9 553.7 559.8 6470.3 6517.7 6533.2 6544.1 6604.5 9800.8 10534.8 11337.7 11550.0 11655.1 10880.0 11274.8 11409.3 11452.2 11582.7 8567.9 8601.3 8646.3 8741.6 8883.0 8452.5 8524.5 8588.0 8644.6 8685.4 10055.3 10095.1 10166.8 10170.3 10237.5 10083.8 10410.0 10624.4 10677.4 10787.6 7240.3 7275.1 7773.3 7976.4 8143.4 8139.9 8790.9 8862.9 8937.2 9092.2 7598.3 7782.8 7798.6 7818.5 7910.2 6336.4 7649.0 7655.6 7691.2 7798.4 8242.9 8330.2 8352.2 8387.7 8388.1 7253.7 7417.9 7457.4 7504.4 7544.2 6424.9 7549.7 7563.8 7605.6 7615.2 16:01:21 INFO - PROCESS | 1667 | 16:01:21 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:6079.00 Median:6923.95 stddev:1950.91 (28.2%) stddev-sans-first:1908.22 16:01:21 INFO - PROCESS | 1667 | Values: 339.2 339.4 344.5 346.6 347.1 6869.3 6895.9 6925.5 6956.0 6957.4 6889.9 6923.4 6924.4 6938.8 6958.8 4186.9 4884.3 5002.6 5072.3 5099.5 5055.6 5073.3 5094.5 5109.5 5110.5 5032.3 5040.9 5051.0 5059.2 5066.4 5028.6 5029.4 5036.4 5039.7 5047.1 4396.9 4405.4 4410.4 4421.5 4422.4 6537.8 6599.1 6732.5 6732.8 6783.0 5977.0 6023.8 6025.6 6027.5 6043.5 6004.6 7427.4 7459.5 7461.6 7480.5 4909.2 4946.5 4947.5 4967.9 4972.8 7056.9 7256.6 7469.0 7907.7 7935.8 4781.6 4895.5 4975.6 4990.0 5003.8 4158.8 5064.2 5069.3 5074.6 5107.1 5911.3 5985.2 5989.4 6005.3 6005.6 7441.8 7471.7 7482.6 7494.6 7504.8 1732.6 1751.6 1773.8 1801.1 1808.3 973.0 975.6 1032.6 1109.4 1153.8 7039.5 7040.9 7077.7 7092.2 7108.0 6362.6 7635.1 7732.9 7738.4 7759.3 7041.5 7078.0 7095.4 7096.1 7108.0 3941.0 4043.8 4086.8 4087.0 4087.3 8074.6 8123.2 8161.9 8256.8 8257.9 8075.7 8091.8 8093.9 8098.9 8110.8 8208.6 8209.4 8211.3 8222.5 8227.6 6264.7 7517.8 7812.5 7839.3 7844.3 7660.1 7686.2 7687.5 7687.5 7691.2 6613.3 8148.4 8155.8 8169.7 8183.3 4899.6 5035.3 5105.9 5115.1 5127.0 6207.9 7502.8 7678.7 7686.7 7723.8 7387.7 7424.5 7489.7 7516.9 7524.8 7450.9 7480.9 7497.3 7517.4 7525.0 7044.3 7322.4 7334.3 7363.6 7370.3 6616.5 6998.0 7469.9 7515.6 7523.8 5850.9 6545.0 6748.8 7318.2 7319.4 16:01:21 INFO - PROCESS | 1667 | 16:01:21 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:18753.23 Median:558.13 stddev:26372.90 (4725.2%) stddev-sans-first:26412.01 16:01:21 INFO - PROCESS | 1667 | Values: 691.4 702.3 733.4 753.6 779.6 13479.5 13620.3 13679.5 13704.8 13815.9 14531.3 16411.7 16751.9 16782.9 16820.7 798.3 960.5 969.0 973.9 979.5 985.5 988.3 991.4 992.8 994.4 1014.6 1046.3 1049.5 1049.9 1050.7 1021.4 1039.0 1044.1 1044.2 1046.6 766.5 776.1 776.5 778.6 780.3 15465.1 15558.9 15747.7 15846.6 15861.8 12967.3 13058.5 13144.1 13157.5 13243.2 10281.0 10472.0 10490.7 10499.9 10567.5 3431.1 3562.9 3582.1 3622.1 3673.7 66482.0 85202.0 85412.0 86462.1 87366.6 9577.4 9744.1 9795.1 9930.1 9956.0 21531.2 21748.0 22001.8 22106.8 22108.0 7460.8 7543.5 7616.3 7620.8 7641.4 4171.5 4195.8 4212.3 4224.9 4296.3 3699.4 3741.3 3741.4 3743.1 3775.1 578.7 665.4 671.1 675.8 679.8 575.0 576.3 607.6 612.3 622.9 8325.2 8431.9 8437.5 8465.0 8512.0 541.3 581.5 611.5 613.9 625.0 3419.8 3733.4 3891.0 3994.7 4111.8 74445.6 91069.0 96467.5 97106.0 98386.5 78945.0 79100.6 80431.6 80464.6 80505.0 72455.6 75169.6 75565.0 75804.6 76271.2 7557.2 8614.6 8626.9 8846.4 8989.3 40179.0 51485.5 53233.0 53310.2 54002.5 57465.0 70401.3 73376.0 75538.0 75749.5 11127.2 11228.5 11320.8 11390.1 11758.9 12181.9 12326.9 12496.4 12590.7 12700.0 11566.9 11621.9 11740.5 11754.7 12107.9 11376.8 11843.8 11883.8 11963.4 12011.6 9530.0 10547.2 10738.8 11580.8 12163.4 8406.0 8582.5 9313.9 9953.2 10079.4 9445.0 9851.3 9893.2 9898.6 9967.1 16:01:21 INFO - PROCESS | 1667 | -------- Summary: end -------- 16:01:21 INFO - PROCESS | 1667 | 16:01:21 INFO - TEST-INFO | 1667: exit 0 16:01:21 INFO - TEST-OK | dromaeo_css | took 1108937ms 16:01:21 INFO - TEST-START | kraken 16:01:21 INFO - operating with platform_type : mac_ 16:01:21 INFO - Initialising browser for kraken test... 16:01:21 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpZXC3Mb/profile http://localhost:49278/getInfo.html) 16:01:24 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 16:01:24 INFO - PROCESS | 1678 | colorDepth:24 16:01:24 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 16:01:24 INFO - PROCESS | 1678 | __metrics 16:01:24 ERROR - PROCESS | 1678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 16:01:24 INFO - TEST-INFO | 1678: exit 0 16:01:24 INFO - Browser initialized. 16:01:24 INFO - Running cycle 1/1 for kraken test... 16:01:24 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpZXC3Mb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:01:36 INFO - PROCESS | 1682 | RSS: Main: 167997440 16:01:36 INFO - PROCESS | 1682 | 16:01:39 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 16:01:39 INFO - PROCESS | 1682 | RSS: Main: 180523008 16:01:39 INFO - PROCESS | 1682 | 16:01:42 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 16:01:42 INFO - PROCESS | 1682 | RSS: Main: 188928000 16:01:42 INFO - PROCESS | 1682 | 16:01:46 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 16:01:46 INFO - PROCESS | 1682 | RSS: Main: 192802816 16:01:46 INFO - PROCESS | 1682 | 16:01:48 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 16:01:49 INFO - PROCESS | 1682 | RSS: Main: 193867776 16:01:49 INFO - PROCESS | 1682 | 16:01:51 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:01:52 INFO - PROCESS | 1682 | RSS: Main: 194551808 16:01:52 INFO - PROCESS | 1682 | 16:01:54 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 16:01:55 INFO - PROCESS | 1682 | RSS: Main: 194859008 16:01:55 INFO - PROCESS | 1682 | 16:01:58 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 16:01:58 INFO - PROCESS | 1682 | RSS: Main: 194666496 16:01:58 INFO - PROCESS | 1682 | 16:02:01 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 16:02:02 INFO - PROCESS | 1682 | RSS: Main: 194588672 16:02:02 INFO - PROCESS | 1682 | 16:02:03 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:02:04 INFO - PROCESS | 1682 | RSS: Main: 194310144 16:02:04 INFO - PROCESS | 1682 | 16:02:06 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:02:06 INFO - PROCESS | 1682 | RSS: Main: 195096576 16:02:06 INFO - PROCESS | 1682 | 16:02:08 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:02:09 INFO - PROCESS | 1682 | RSS: Main: 195334144 16:02:09 INFO - PROCESS | 1682 | 16:02:12 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:02:12 INFO - PROCESS | 1682 | RSS: Main: 195293184 16:02:12 INFO - PROCESS | 1682 | 16:02:15 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:02:15 INFO - PROCESS | 1682 | RSS: Main: 194789376 16:02:15 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 16:02:17 INFO - PROCESS | 1682 | RSS: Main: 196722688 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | __start_tp_report 16:02:17 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 16:02:17 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 16:02:17 INFO - PROCESS | 1682 | |i|pagename|runs| 16:02:17 INFO - PROCESS | 1682 | |0;ai-astar;116;112;116;116;116;129;115;115;115;115 16:02:17 INFO - PROCESS | 1682 | |1;audio-beat-detection;148;120;144;149;130;165;143;151;156;148 16:02:17 INFO - PROCESS | 1682 | |2;audio-dft;191;193;196;201;188;235;254;266;266;198 16:02:17 INFO - PROCESS | 1682 | |3;audio-fft;69;86;104;113;57;57;57;59;117;57 16:02:17 INFO - PROCESS | 1682 | |4;audio-oscillator;83;78;122;80;79;85;116;79;79;118 16:02:17 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;89;86;88;86;88;85;89;86;89;85 16:02:17 INFO - PROCESS | 1682 | |6;imaging-darkroom;95;94;95;94;97;94;96;97;95;104 16:02:17 INFO - PROCESS | 1682 | |7;imaging-desaturate;119;121;91;90;93;92;90;121;144;147 16:02:17 INFO - PROCESS | 1682 | |8;json-parse-financial;57;61;58;58;60;59;60;68;57;54 16:02:17 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;85;88;97;64;79;72;69;64;61;81 16:02:17 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;83;84;83;85;83;84;83;82;82;84 16:02:17 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;124;133;134;113;136;136;136;130;132;116 16:02:17 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;123;136;135;135;137;134;124;152;172;121 16:02:17 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;67;65;68;67;69;68;64;70;84;90 16:02:17 INFO - PROCESS | 1682 | __end_tp_report 16:02:17 INFO - PROCESS | 1682 | __start_cc_report 16:02:17 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,923 16:02:17 INFO - PROCESS | 1682 | __end_cc_report 16:02:17 INFO - PROCESS | 1682 | __startTimestamp1462834937801__endTimestamp 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | ------- Summary: start ------- 16:02:17 INFO - PROCESS | 1682 | Number of tests: 14 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:116.50 Median:116.00 stddev:4.55 (3.9%) stddev-sans-first:4.82 16:02:17 INFO - PROCESS | 1682 | Values: 116.0 112.0 116.0 116.0 116.0 129.0 115.0 115.0 115.0 115.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:145.40 Median:148.50 stddev:12.67 (8.5%) stddev-sans-first:13.40 16:02:17 INFO - PROCESS | 1682 | Values: 148.0 120.0 144.0 149.0 130.0 165.0 143.0 151.0 156.0 148.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:218.80 Median:218.00 stddev:32.68 (15.0%) stddev-sans-first:33.08 16:02:17 INFO - PROCESS | 1682 | Values: 191.0 193.0 196.0 201.0 188.0 235.0 254.0 266.0 266.0 198.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:77.60 Median:57.00 stddev:25.14 (44.1%) stddev-sans-first:26.48 16:02:17 INFO - PROCESS | 1682 | Values: 69.0 86.0 104.0 113.0 57.0 57.0 57.0 59.0 117.0 57.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:79.00 stddev:18.65 (23.6%) stddev-sans-first:19.50 16:02:17 INFO - PROCESS | 1682 | Values: 83.0 78.0 122.0 80.0 79.0 85.0 116.0 79.0 79.0 118.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:88.00 stddev:1.66 (1.9%) stddev-sans-first:1.62 16:02:17 INFO - PROCESS | 1682 | Values: 89.0 86.0 88.0 86.0 88.0 85.0 89.0 86.0 89.0 85.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:95.00 stddev:3.00 (3.2%) stddev-sans-first:3.15 16:02:17 INFO - PROCESS | 1682 | Values: 95.0 94.0 95.0 94.0 97.0 94.0 96.0 97.0 95.0 104.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:110.80 Median:90.00 stddev:22.65 (25.2%) stddev-sans-first:23.82 16:02:17 INFO - PROCESS | 1682 | Values: 119.0 121.0 91.0 90.0 93.0 92.0 90.0 121.0 144.0 147.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:59.20 Median:59.50 stddev:3.68 (6.2%) stddev-sans-first:3.81 16:02:17 INFO - PROCESS | 1682 | Values: 57.0 61.0 58.0 58.0 60.0 59.0 60.0 68.0 57.0 54.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:76.00 Median:80.00 stddev:11.92 (14.9%) stddev-sans-first:12.19 16:02:17 INFO - PROCESS | 1682 | Values: 85.0 88.0 97.0 64.0 79.0 72.0 69.0 64.0 61.0 81.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:83.50 stddev:0.95 (1.1%) stddev-sans-first:1.00 16:02:17 INFO - PROCESS | 1682 | Values: 83.0 84.0 83.0 85.0 83.0 84.0 83.0 82.0 82.0 84.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:129.00 Median:133.50 stddev:8.49 (6.4%) stddev-sans-first:8.80 16:02:17 INFO - PROCESS | 1682 | Values: 124.0 133.0 134.0 113.0 136.0 136.0 136.0 130.0 132.0 116.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.90 Median:135.50 stddev:15.23 (11.2%) stddev-sans-first:15.31 16:02:17 INFO - PROCESS | 1682 | Values: 123.0 136.0 135.0 135.0 137.0 134.0 124.0 152.0 172.0 121.0 16:02:17 INFO - PROCESS | 1682 | 16:02:17 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:68.50 stddev:8.63 (12.6%) stddev-sans-first:9.01 16:02:17 INFO - PROCESS | 1682 | Values: 67.0 65.0 68.0 67.0 69.0 68.0 64.0 70.0 84.0 90.0 16:02:17 INFO - PROCESS | 1682 | -------- Summary: end -------- 16:02:17 INFO - PROCESS | 1682 | 16:02:18 INFO - TEST-INFO | 1682: exit 0 16:02:18 INFO - TEST-OK | kraken | took 57012ms 16:02:18 INFO - SUITE-END | took 1165s 16:02:18 INFO - Completed test suite (00:19:26) 16:02:18 INFO - javascript benchmark 16:02:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3625.3111908737337, 3639.836207370668, 3647.3981793004154, 3700.0749812546874, 3701.148735790768, 5718.7426565804535, 5807.970960145204, 5808.796692115766, 5870.295564532256, 5873.2658417697785, 5794.565407594426, 5801.274840644919, 5839.182514447981, 5844.26946631671, 5850.4734573888345, 5234.92147617786, 5273.525382715555, 5332.226827110067, 5346.1713434417725, 5368.32895888014, 5256.8160114396005, 5315.813946511854, 5359.5712343012465, 5368.436314187013, 5393.919091213635, 5320.813771517997, 5349.545288650469, 5402.108652072403, 5422.15956526739, 5430.104032834597, 5385.380681221671, 5390.73046347683, 5390.97304513475, 5404.972975135119, 5408.756605952742, 5127.2052831811125, 5225.790968361261, 5249.4488078751565, 5309.681419114865, 5384.838454846361, 5555.2500412444615, 5658.830235092953, 5689.0, 5713.285839270091, 5718.25662663851, 6194.25668919728, 6244.500439964837, 6273.184486016834, 6276.309605943343, 6278.089676996856, 6150.1389805426825, 6153.353897840707, 6197.721102550396, 6218.906716399258, 6250.9062364064575, 5396.947595218934, 5564.749586268627, 5956.702164891738, 5979.983402821531, 5994.730237139339, 6347.428731414103, 6353.14232578596, 6361.936380636227, 6376.075469057054, 6419.743210271537, 6134.00801433197, 6168.722407491674, 6200.937990620127, 6258.3428739982555, 6287.779927702509, 6231.470325022333, 6240.00159974411, 6245.906311405332, 6260.280067792262, 6266.248050234001, 6121.020636698196, 6199.411055949678, 6248.812535623939, 6271.341509141565, 6272.5922815016875, 4846.5153484651255, 5046.59627229828, 5495.395506494282, 6190.3190649028575, 6206.348333425016, 4934.259861020804, 5021.698698078127, 5038.79844806211, 5047.369078865142, 5090.872728181751, 4414.0, 4422.535633758409, 4538.931916021263, 4543.840965566189, 4552.362669226311, 4800.279958006327, 5706.429357064343, 5713.085906255063, 5787.276590426197, 5872.677002764804, 6196.628202307876, 6198.101275315144, 6210.161628180138, 6222.12890195373, 6222.315545290015, 5726.284214473191, 5838.562107841929, 5906.055031195074, 5933.0, 5948.405159484017, 5444.047291723932, 5472.671639701631, 5501.559875209973, 5514.834554963358, 5571.860703482445, 6441.066045423388, 6464.353564643498, 6466.094746735461, 6492.026196070533, 6500.707468163944, 6159.938400616115, 6187.969060154671, 6211.689415529115, 6234.532410069172, 6241.563090583615, 6402.167718196604, 6410.679466026587, 6438.163038804925, 6439.67801609927, 6446.161998940108, 5123.359580052494, 6271.996480563088, 6281.842953926189, 6295.622262664255, 6308.558400911892, 6149.631022138686, 6176.320604733386, 6199.907001394892, 6224.875502489835, 6254.0, 5160.0, 5576.386597474356, 6141.385861413823, 6212.844678882883, 6234.657093859882, 5044.470330615232, 5755.884882302246, 6165.383461653799, 6208.72060757258, 6215.96892015537, 5735.397783232867, 5888.293404791452, 5929.495992840657, 6040.909386359296, 6094.969525152346, 4508.774561271989, 6103.542234332532, 6143.324234334309, 6172.753089876354, 6187.659678717714, 5935.169076329231, 6039.48664363534, 6086.02623580225, 6108.969455152874, 6119.388061193845], "unit": "score", "name": "dojo.html", "value": 5727.802692315477}, {"lowerIsBetter": false, "replicates": [476.3783262841992, 617.9772476551309, 623.3019018699055, 677.1907570453309, 678.6131904814256, 40151.79924100379, 40752.18495630086, 40759.388609170885, 40799.592004079954, 40957.0, 42250.0, 42312.57687423125, 42327.365089523606, 42436.0, 42949.57050429495, 9673.661421850255, 9891.554880030415, 9894.50527473627, 9950.154236889874, 10006.249531285146, 9813.607455701798, 10013.649522266722, 10084.14284785794, 10095.596176152945, 10122.89877101227, 8542.316614670843, 10033.548490317953, 10120.746981325488, 10122.190224782, 10140.391576505433, 9093.727188184299, 9529.856417229978, 10008.049235322633, 10110.494475276244, 10123.0, 9961.950190249003, 10003.0, 10092.192624590089, 10095.848562271498, 10125.29122961393, 29821.850890745623, 35478.29043419143, 37725.62274377249, 41037.589624103675, 41831.37252941208, 3223.4842425212087, 3328.983355083233, 3399.8640054397792, 3500.7549471537004, 3512.244867353506, 26101.869490652614, 28142.437151257065, 28147.718522814714, 28934.0, 29565.113046608636, 2327.3483424641126, 2791.7347851954237, 2809.4100238949845, 2873.6551613806478, 3012.5932999045285, 336561.0, 336991.0, 337823.0, 337826.0, 337906.0, 48844.267335989985, 49361.012779744924, 49364.0, 70023.0, 71333.57332853314, 39547.40678889819, 47411.5258847414, 47757.0, 47833.52166478291, 48060.27909581359, 16934.661306774044, 17090.48728538146, 17259.0, 17373.47879563615, 17404.303827846998, 13419.261940593362, 13441.193528388329, 13495.0, 13564.186148830904, 13648.93175534116, 15699.058056516646, 15818.60453488649, 15832.525024249291, 15857.286422110801, 15898.284577194054, 5575.470330318613, 6518.511111666644, 6590.8352291193105, 6631.3700198481065, 6669.498513280567, 1030.7474668706066, 1032.396048311746, 1034.3018462537757, 1172.355204637446, 1182.4619797992066, 29974.0, 30160.0, 31057.0682879514, 31191.0, 31972.200694982348, 1066.3015724700333, 1085.4898197846999, 1087.0434018064211, 1154.8440960470398, 1158.80879654856, 15158.924205379122, 15225.695486090217, 15602.609934751723, 15629.765553516707, 15973.361065557248, 449955.0, 485414.0, 539861.0, 548368.0, 548887.0, 61930.0, 61964.76070478565, 63764.68117659475, 63778.0, 63802.68098659477, 1186.976260474803, 1530.188999830092, 1532.8926975111858, 1541.5837723814507, 1590.9204539772966, 28425.715742842305, 28599.0, 28871.85564072166, 30433.69566304397, 30924.381512370077, 55774.163387548404, 56129.71935140298, 56293.0, 56837.0, 57098.42901570931, 425487.0, 426418.0, 438398.0, 438943.0, 439262.8036859795, 35060.474092888115, 35986.64013359833, 36059.27881442409, 36353.09117271983, 36564.63435365719, 47847.760761197365, 47894.0, 48145.518544814106, 49059.264111039076, 49370.39676882853, 39428.80285598554, 39821.80089099536, 40130.0, 40145.59854401419, 40327.1934561313, 39022.4146637795, 39468.81593552198, 39547.40678889876, 39764.80117599509, 40012.1997560053, 39940.800295998335, 40220.1955960885, 40288.0, 40883.18233635251, 42007.1598568033, 31504.684953150176, 33196.004119876445, 33293.16767080843, 33300.333993320484, 33304.666953330154, 25464.981400743764, 25473.28922287759, 25692.229233123035, 25874.870625646752, 26038.86980565085], "unit": "score", "name": "ext.html", "value": 19704.43157891066}, {"lowerIsBetter": false, "replicates": [337.85134540802045, 353.1947160474119, 377.58970246130696, 382.0620376974527, 388.98833035008926, 20034.0, 20243.392698219057, 20495.487612809688, 21119.472013199676, 21138.788612113873, 16331.755023674654, 20371.28700495483, 20445.48886277844, 20971.0, 21458.89270553645, 5367.946320536803, 5645.096784514479, 5937.970310148443, 6023.668698221596, 6112.602680825732, 5806.535477161838, 5892.911606325909, 5924.703764811764, 5956.583039187237, 6086.360932102132, 5411.783528658849, 5705.343885453164, 5899.085641725518, 5998.8800223995495, 6000.489958353545, 6061.242344706911, 6138.754449822001, 6149.938500614981, 6167.87664246717, 6181.567290289681, 5862.296524417097, 5895.616784908967, 5943.286805583358, 5955.851103722398, 5985.281766188085, 13718.314084295846, 14101.64745881351, 14122.71754564903, 14186.0, 18318.450446486626, 2261.9886900565557, 2266.614675505168, 2307.2962746362355, 2322.558713844381, 2358.8230882683865, 10030.749231269203, 10380.532876020598, 10467.005634464713, 10534.26260161789, 13101.41043653038, 1788.7316902464734, 1805.1154934082335, 1812.474382429094, 1840.6594779965615, 1875.2499000399812, 102061.48969255181, 103081.0, 104685.42971855428, 110390.44804776004, 110776.4461177689, 11438.370889601152, 11490.655280341603, 11684.88315116855, 11826.231294965799, 11934.0, 14477.203753793643, 14610.63473413152, 14620.195889225983, 14629.0, 14654.926725366306, 8248.257656810916, 8382.036065852382, 9032.45805251687, 9195.0, 9229.676961306322, 9401.153896149379, 9421.62313507452, 9447.433154010781, 9453.716388508356, 9536.28477864163, 3828.578856325802, 4544.0, 4620.745858977789, 4637.5130611286, 4665.626749860003, 1936.5061909213061, 1986.106252186522, 1992.2529051605648, 2011.8088781565727, 2015.9294424695065, 803.8392321535716, 1047.528612124547, 1093.868735751715, 1097.1442275025493, 1097.8902109789117, 20946.89526552388, 21673.89163054206, 21708.240211592518, 21798.877263842984, 22202.888985554968, 956.9569369378395, 957.711877524742, 1001.9549120289604, 1052.4790228836775, 1054.6361505280665, 11332.263402878787, 11769.88230117705, 11773.058155347551, 11781.76436471283, 11843.0, 684538.9447612524, 693026.0, 699313.0, 720219.0, 738047.0, 100076.0, 116861.0, 123580.38209809073, 131178.0, 135707.0, 1020.9285350025575, 1025.195221750922, 1026.7616595821182, 1049.5854137615752, 1052.7894421115807, 19067.80932190716, 19218.615627687646, 19316.420507384802, 19354.903225483784, 19419.4174174775, 51599.0, 52349.42951711455, 52722.472775273294, 52763.0, 53145.202821956926, 73991.26008739843, 74863.25136748781, 78094.0, 78816.60591697005, 80410.79383809364, 8291.668333266602, 8332.125126861834, 8349.95825020871, 8359.623816928131, 8405.873911891204, 9681.305771490102, 12957.740845183233, 13592.048556600943, 13653.726925461633, 13941.0, 11498.94250528742, 11724.58963936258, 11974.58088966882, 12011.399430028288, 12131.696707582381, 11530.365829879438, 11679.649610511698, 11743.41282935867, 11854.466549005143, 12039.0, 11986.0, 11998.280103193836, 12030.458629361874, 12123.75752484963, 12126.393680316125, 8384.748457546284, 8402.537860417491, 8436.704715334934, 8540.786480337792, 8818.603162857815, 11901.464434100659, 12096.637100886988, 12133.575324863587, 12187.573434929735, 12221.877781222074], "unit": "score", "name": "jquery.html", "value": 9705.030461771834}, {"lowerIsBetter": false, "replicates": [1515.57563882113, 1629.6251862071724, 1654.5367297156797, 2067.10081114715, 2078.1168003598477, 7978.84042319154, 8358.665653373857, 9210.263178945685, 9233.722988310354, 9603.039696030402, 7736.1877002914725, 9213.401128926615, 9284.90715092849, 9384.061593840612, 9405.388649737763, 2522.7351128131513, 2525.6337831014584, 2536.809739269553, 2549.120553409071, 2549.872506374674, 2569.717331093578, 2608.67391576053, 2635.9604605930926, 2636.986815065922, 2643.510950474159, 2588.3011586871535, 2591.1967290140024, 2608.67391576053, 2616.7906567474556, 2619.3058839407477, 2592.313037045194, 2613.73862613739, 2628.158989123481, 2631.776299014586, 2633.5654616988268, 2596.883140258693, 2596.9220923372327, 2616.856072915989, 2628.0670362021397, 2644.074573899139, 9349.345545811795, 9459.952700236523, 9466.479343636098, 9492.288078394147, 9505.239580833517, 5833.445822646841, 5944.0, 5979.760809567612, 6032.155498230251, 6050.576459647827, 3651.050726811022, 3706.721995850322, 3831.3869780834934, 3837.0599203195115, 3838.75048121874, 1080.4327727942814, 1089.2375337263948, 1090.2640717515644, 1130.9547618095266, 1133.2463911498844, 12670.873291267062, 12707.428165732565, 12737.93631031839, 12743.936280318632, 12791.680207994781, 5106.412762532282, 5137.717425541631, 5214.947850521522, 5235.214717792362, 5304.469553044664, 10900.182486313559, 10926.344419334866, 10935.343879367105, 10945.83581246282, 10973.0, 4955.975220123948, 5750.88498230033, 5946.256717910262, 5973.611715238496, 5985.0, 4276.8289268429535, 4748.145333839942, 5123.718195499208, 5254.579633629301, 5328.37484567203, 3518.5425894633536, 4116.835326586963, 4154.626083652486, 4226.450561426995, 4260.723053001545, 1382.3917476310392, 1382.7372799168127, 1395.720855828818, 1399.1465206224195, 1399.15351212517, 1379.6412932637386, 1413.5052731543876, 1433.7562614355584, 1448.5292280008828, 1470.477980316972, 8474.661013559511, 8584.227419532272, 8664.523451210243, 8726.476411415335, 8805.779855503664, 507.67508794371633, 547.7507733981029, 552.8728392469754, 553.6871667507845, 559.846042338352, 6470.288268290485, 6517.706703198368, 6533.150690410216, 6544.083828264047, 6604.537682362188, 9800.754981125529, 10534.841977370346, 11337.696164940973, 11550.0, 11655.067594592412, 10880.0, 11274.774504509864, 11409.25839820409, 11452.198346115692, 11582.6525204244, 8567.914320856711, 8601.31189504838, 8646.265067469336, 8741.562921853882, 8882.955585222033, 8452.450590711584, 8524.531150786519, 8588.0, 8644.567771611268, 8685.392022558613, 10055.346402483816, 10095.141912937483, 10166.847497287694, 10170.288079834341, 10237.488125593542, 10083.848742268725, 10409.998551947618, 10624.36253824773, 10677.359358438518, 10787.568497260021, 7240.348368646847, 7275.090613673291, 7773.261540153676, 7976.441649084509, 8143.42995990275, 8139.877901831359, 8790.868136977822, 8862.911370886466, 8937.150970657776, 9092.227160691415, 7598.316151546387, 7782.805429864298, 7798.610069496389, 7818.491798033109, 7910.169432209535, 6336.398042185985, 7649.005629268159, 7655.57894315818, 7691.1539730628465, 7798.37612990948, 8242.876356854533, 8330.166983301622, 8352.248297653241, 8387.748367548984, 8388.119247478928, 7253.746118886025, 7417.851642967003, 7457.440691948234, 7504.437167212372, 7544.170141284345, 6424.871502570016, 7549.7357592483995, 7563.848723025619, 7605.581693006938, 7615.200403957726], "unit": "score", "name": "mootools.html", "value": 5126.136331479574}, {"lowerIsBetter": false, "replicates": [339.21302578019, 339.4043453738688, 344.5073544830894, 346.5564077980186, 347.1304382521781, 6869.278725733801, 6895.896561551581, 6925.480588955823, 6955.965220173892, 6957.408620267277, 6889.931100688992, 6923.446124310056, 6924.446044316455, 6938.826529336769, 6958.756443524478, 4186.853460128896, 4884.267359896026, 5002.574781143607, 5072.289879416885, 5099.490050994889, 5055.5702765265, 5073.3404657394485, 5094.46508116648, 5109.489051094898, 5110.488951104897, 5032.345795046657, 5040.924386134211, 5051.0150520648485, 5059.16523773577, 5066.392032956032, 5028.647994640377, 5029.3713285839085, 5036.395632524121, 5039.672421292623, 5047.0915235257635, 4396.944733263994, 4405.383246345515, 4410.42664453619, 4421.513633500313, 4422.425084738963, 6537.803865884031, 6599.142111525519, 6732.528722989393, 6732.764353247662, 6782.966085169592, 5977.0, 6023.819285421444, 6025.638461692269, 6027.547933904974, 6043.546733994924, 6004.5796794224425, 7427.405807535438, 7459.515131516434, 7461.589612571306, 7480.513766605153, 4909.239067944439, 4946.505349465025, 4947.50524947506, 4967.900641987176, 4972.776225069844, 7056.858862822714, 7256.624032062584, 7468.9626551866895, 7907.683692652287, 7935.761927142137, 4781.617470602343, 4895.485973972753, 4975.5522003019905, 4990.0, 5003.824866129668, 4158.792060396968, 5064.215046667772, 5069.315642388304, 5074.619403544749, 5107.131787596118, 5911.261092363455, 5985.191999080069, 5989.431004054608, 6005.3394126646035, 6005.579609427386, 7441.8139546511775, 7471.738489152854, 7482.625868706543, 7494.587797671072, 7504.849903001909, 1732.6015016546264, 1751.5653241993639, 1773.7782777152856, 1801.1354549816162, 1808.2947650416347, 972.9756756081155, 975.6000039983714, 1032.6282538286212, 1109.4286442482128, 1153.7519433321709, 7039.472039597051, 7040.894386584206, 7077.681504332318, 7092.184398794101, 7108.040414544073, 6362.586431881913, 7635.121960974448, 7732.9226707732205, 7738.380929525624, 7759.340456061298, 7041.507094503438, 7078.0, 7095.396891264197, 7096.148462184571, 7108.040414544073, 3940.960590394059, 4043.757374557536, 4086.7752273625238, 4086.9795651021554, 4087.346024636044, 8074.555899425588, 8123.18768123183, 8161.918380816116, 8256.752297431087, 8257.876131858027, 8075.717349892726, 8091.838163236703, 8093.919060809317, 8098.919010809816, 8110.797230069295, 8208.630611622371, 8209.38429617793, 8211.260986511243, 8222.465539740017, 8227.588620568948, 6264.68676566179, 7517.849643007218, 7812.492188007761, 7839.33365663925, 7844.254795794391, 7660.131633525034, 7686.154523002353, 7687.5387476751575, 7687.5387476751575, 7691.230876912264, 6613.305602911625, 8148.429609927248, 8155.755327340189, 8169.673213071411, 8183.345332373515, 4899.608031357554, 5035.345405097314, 5105.948940510547, 5115.079285728604, 5127.025865085622, 6207.906881396692, 7502.774916752506, 7678.654460549177, 7686.730964416219, 7723.7682869514, 7387.704491820267, 7424.480286379901, 7489.737859174903, 7516.924830751841, 7524.774256772305, 7450.92549074524, 7480.850382992201, 7497.2877576630135, 7517.436192285491, 7525.0, 7044.260352662896, 7322.414206863332, 7334.3032411920785, 7363.558186508612, 7370.262973702587, 6616.470682345304, 6998.0, 7469.88795168062, 7515.586642734484, 7523.774286771406, 5850.882982340415, 6544.9672751635935, 6748.751480976035, 7318.1949985502615, 7319.4144468443465], "unit": "score", "name": "prototype.html", "value": 5422.202484266744}, {"lowerIsBetter": false, "replicates": [691.4433880726014, 702.2696395748421, 733.3949491669371, 753.6495529578744, 779.6491578789543, 13479.528216512424, 13620.250886201267, 13679.452821887113, 13704.794428083562, 13815.861841381584, 14531.346089425975, 16411.67176656469, 16751.911125776845, 16782.916085419554, 16820.66358672829, 798.3453568074182, 960.5341409416445, 968.9563969621348, 973.9026097390275, 979.5298256836722, 985.4924713772413, 988.2884323287221, 991.3605724307818, 992.7617371830781, 994.3636072913341, 1014.5688082564917, 1046.2519298701427, 1049.4857519815273, 1049.8635177426925, 1050.6637875879721, 1021.3616489693941, 1038.9636362727344, 1044.0864243786687, 1044.1803184500206, 1046.5604446132643, 766.4634755671052, 776.0919723923024, 776.5185584937318, 778.5912395992093, 780.3406121827047, 15465.149416782073, 15558.9222053889, 15747.68504629901, 15846.603834904105, 15861.841381586151, 12967.286799226133, 13058.477660893554, 13144.079914405995, 13157.53948611794, 13243.205407675474, 10281.0, 10472.0, 10490.685279441628, 10499.94750026245, 10567.471626418648, 3431.1250631088915, 3562.8931132066077, 3582.0507565495163, 3622.0582648511313, 3673.687736542397, 66482.0, 85202.0, 85412.0, 86462.13537864604, 87366.56316718376, 9577.377470464398, 9744.07431294026, 9795.118439340493, 9930.106290433896, 9955.950220248853, 21531.246406375703, 21748.02133903978, 22001.779982200293, 22106.778932210793, 22108.00513976875, 7460.813479663052, 7543.47195696296, 7616.314531692175, 7620.847583048308, 7641.35048520882, 4171.478565179353, 4195.832166713359, 4212.262854000538, 4224.894377640584, 4296.2696341621995, 3699.389600715852, 3741.326561218952, 3741.3639681254253, 3743.0642339415144, 3775.112848480605, 578.7077525849419, 665.3679004945321, 671.0739179931663, 675.7533500272365, 679.823245956055, 574.9995008684941, 576.2854060964457, 607.6080927801544, 612.2805703298695, 622.9439350458481, 8325.18937796718, 8431.915680843236, 8437.493750374875, 8465.0, 8512.029483007114, 541.2557733116964, 581.514435446407, 611.4649681528663, 613.8894998900241, 624.9562530622812, 3419.7777144486026, 3733.3653278942643, 3891.0, 3994.7003974702016, 4111.753294802322, 74445.62777186079, 91069.0, 96467.51766241265, 97106.0, 98386.5080674592, 78945.0, 79100.60449697715, 80431.59784201042, 80464.59767701125, 80505.0, 72455.6377218121, 75169.62415187889, 75565.0, 75804.62097689587, 76271.23728762641, 7557.206493318123, 8614.569271536273, 8626.91373086261, 8846.424982376126, 8989.280857531512, 40179.0, 51485.48514514957, 53233.0, 53310.200346994054, 54002.459975399746, 57465.0, 70401.29598703947, 73376.0, 75538.0, 75749.45503269821, 11127.221094523306, 11228.494717737558, 11320.773584528428, 11390.145739069769, 11758.882411175779, 12181.939090304491, 12326.938365308117, 12496.437660305126, 12590.685232869251, 12700.0, 11566.884331156582, 11621.941890290494, 11740.471678774305, 11754.70613234676, 12107.878921210675, 11376.829347559627, 11843.822342665038, 11883.76232475363, 11963.401829908644, 12011.579594714492, 9529.952350238482, 10547.208959328234, 10738.785224295314, 11580.826287605862, 12163.391830408622, 8405.95797021011, 8582.485050896967, 9313.906860931304, 9953.15398191133, 10079.395236285847, 9445.0, 9851.261155413229, 9893.159081478156, 9898.604055837974, 9967.10296073357], "unit": "score", "name": "yui.html", "value": 7127.157181299742}], "name": "dromaeo_css", "value": 7751.942621304769}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 112.0, 116.0, 116.0, 116.0, 129.0, 115.0, 115.0, 115.0, 115.0], "unit": "score", "name": "ai-astar", "value": 116.5}, {"lowerIsBetter": true, "replicates": [148.0, 120.0, 144.0, 149.0, 130.0, 165.0, 143.0, 151.0, 156.0, 148.0], "unit": "score", "name": "audio-beat-detection", "value": 145.4}, {"lowerIsBetter": true, "replicates": [191.0, 193.0, 196.0, 201.0, 188.0, 235.0, 254.0, 266.0, 266.0, 198.0], "unit": "score", "name": "audio-dft", "value": 218.8}, {"lowerIsBetter": true, "replicates": [69.0, 86.0, 104.0, 113.0, 57.0, 57.0, 57.0, 59.0, 117.0, 57.0], "unit": "score", "name": "audio-fft", "value": 77.6}, {"lowerIsBetter": true, "replicates": [83.0, 78.0, 122.0, 80.0, 79.0, 85.0, 116.0, 79.0, 79.0, 118.0], "unit": "score", "name": "audio-oscillator", "value": 91.9}, {"lowerIsBetter": true, "replicates": [89.0, 86.0, 88.0, 86.0, 88.0, 85.0, 89.0, 86.0, 89.0, 85.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 87.1}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 95.0, 94.0, 97.0, 94.0, 96.0, 97.0, 95.0, 104.0], "unit": "score", "name": "imaging-darkroom", "value": 96.1}, {"lowerIsBetter": true, "replicates": [119.0, 121.0, 91.0, 90.0, 93.0, 92.0, 90.0, 121.0, 144.0, 147.0], "unit": "score", "name": "imaging-desaturate", "value": 110.8}, {"lowerIsBetter": true, "replicates": [57.0, 61.0, 58.0, 58.0, 60.0, 59.0, 60.0, 68.0, 57.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 59.2}, {"lowerIsBetter": true, "replicates": [85.0, 88.0, 97.0, 64.0, 79.0, 72.0, 69.0, 64.0, 61.0, 81.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 76.0}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 83.0, 85.0, 83.0, 84.0, 83.0, 82.0, 82.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.3}, {"lowerIsBetter": true, "replicates": [124.0, 133.0, 134.0, 113.0, 136.0, 136.0, 136.0, 130.0, 132.0, 116.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 129.0}, {"lowerIsBetter": true, "replicates": [123.0, 136.0, 135.0, 135.0, 137.0, 134.0, 124.0, 152.0, 172.0, 121.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 136.9}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 68.0, 67.0, 69.0, 68.0, 64.0, 70.0, 84.0, 90.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 71.2}], "name": "kraken", "value": 1499.8000000000002}]} 16:02:19 INFO - Return code: 0 16:02:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:02:19 ERROR - # TBPL SUCCESS # 16:02:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:02:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:02:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:02:19 INFO - 2016-05-09 16:02:19.080 screenresolution[1687:10681] starting screenresolution argv=screenresolution get 16:02:19 INFO - 2016-05-09 16:02:19.083 screenresolution[1687:10681] Display 0: 1600x1200x32@60 16:02:19 INFO - 2016-05-09 16:02:19.089 screenresolution[1688:10684] starting screenresolution argv=screenresolution list 16:02:19 INFO - Available Modes on Display 0 16:02:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:02:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:02:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:02:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:02:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:02:19 INFO - Intel Iris: 16:02:19 INFO - Chipset Model: Intel Iris 16:02:19 INFO - Type: GPU 16:02:19 INFO - Bus: Built-In 16:02:19 INFO - VRAM (Dynamic, Max): 1536 MB 16:02:19 INFO - Vendor: Intel (0x8086) 16:02:19 INFO - Device ID: 0x0a2e 16:02:19 INFO - Revision ID: 0x0009 16:02:19 INFO - Displays: 16:02:19 INFO - Display: 16:02:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:02:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:02:19 INFO - Display Serial Number: 1600x1200 60 16:02:19 INFO - Main Display: Yes 16:02:19 INFO - Mirror: Off 16:02:19 INFO - Online: Yes 16:02:19 INFO - Rotation: Supported 16:02:19 INFO - Return code: 0 16:02:19 INFO - Running post-action listener: _resource_record_post_action 16:02:19 INFO - Running post-run listener: _resource_record_post_run 16:02:20 INFO - Total resource usage - Wall time: 1191s; CPU: 53.0%; Read bytes: 38012416; Write bytes: 511170048; Read time: 389; Write time: 1594 16:02:20 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 206201344; Write bytes: 207910400; Read time: 18778; Write time: 407 16:02:20 INFO - run-tests - Wall time: 1168s; CPU: 53.0%; Read bytes: 36185600; Write bytes: 302227456; Read time: 370; Write time: 1184 16:02:20 INFO - Running post-run listener: _upload_blobber_files 16:02:20 INFO - Blob upload gear active. 16:02:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:02:20 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 16:02:20 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'] 16:02:20 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 16:02:20 INFO - (blobuploader) - INFO - Open directory for files ... 16:02:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:02:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:02:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:22 INFO - (blobuploader) - INFO - Done attempting. 16:02:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:02:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:02:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:23 INFO - (blobuploader) - INFO - Done attempting. 16:02:23 INFO - (blobuploader) - INFO - Iteration through files over. 16:02:23 INFO - Return code: 0 16:02:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:02:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:02:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0acf650c76c615338f0a3fa561dd673dd3f871538642adb9310a7576df05645f1802589b4640e08d765be23e282e7d42f27e030507f6d95f89a367b807aa7937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac29a646c9fbe08614ff72bbe252ad697934039df6748065a620481fde27e9d504d4b561b3de8e173aeec9e5e49383c15cc7f9ec107bf6006762f6776d4df003"} 16:02:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:02:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:02:23 INFO - Contents: 16:02:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0acf650c76c615338f0a3fa561dd673dd3f871538642adb9310a7576df05645f1802589b4640e08d765be23e282e7d42f27e030507f6d95f89a367b807aa7937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac29a646c9fbe08614ff72bbe252ad697934039df6748065a620481fde27e9d504d4b561b3de8e173aeec9e5e49383c15cc7f9ec107bf6006762f6776d4df003"} 16:02:23 INFO - Running post-run listener: copy_logs_to_upload_dir 16:02:23 INFO - Copying logs to upload dir... 16:02:23 INFO - mkdir: /builds/slave/test/build/upload/logs 16:02:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.855484 ========= master_lag: 13.86 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-09 16:02:37.398689) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 16:02:37.402376) ========= 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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0acf650c76c615338f0a3fa561dd673dd3f871538642adb9310a7576df05645f1802589b4640e08d765be23e282e7d42f27e030507f6d95f89a367b807aa7937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac29a646c9fbe08614ff72bbe252ad697934039df6748065a620481fde27e9d504d4b561b3de8e173aeec9e5e49383c15cc7f9ec107bf6006762f6776d4df003"} build_url:https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011259 build_url: 'https://queue.taskcluster.net/v1/task/AGQ3eXOvSyWA6A_Y0JBxVw/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0acf650c76c615338f0a3fa561dd673dd3f871538642adb9310a7576df05645f1802589b4640e08d765be23e282e7d42f27e030507f6d95f89a367b807aa7937", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac29a646c9fbe08614ff72bbe252ad697934039df6748065a620481fde27e9d504d4b561b3de8e173aeec9e5e49383c15cc7f9ec107bf6006762f6776d4df003"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 16:02:37.434795) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:02:37.435090) ========= 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.O6HX4Efzf5/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.9VLFBjmjJL/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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.005145 ========= master_lag: 0.19 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:02:37.630336) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 16:02:37.630633) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 16:02:37.630967) ========= ========= Total master_lag: 14.14 =========